stddev() function crashes on large data sets

Open

#20,944 opened on Mar 15, 2021 0 comments 0 reactions 0 assignees View on GitHub

1.x

Dominant language: Rust
Stars: 31.7k
Forks: 3.7k
Avg merge: 13h 37m
Merged PRs (30d): 8

Description

I have stumbled across some problems with Influx's stddev() function.

By way of background, I have an ESP8266-based monitor sampling grid voltage at 10-second intervals, logging via Mosquitto and Node-Red into InfluxDB 1.8.4 running inside a Docker container on a 4GB Raspberry Pi 4B with a 500MB SSD. The database is approximately 3 years old and has over 9 million rows.

Recently, I wanted to calculate the voltage mean and standard deviation, but the container crashed. The crash was *repeatable* and that led me to investigate further.

My findings suggest that Influx's stddev() function is probably implemented in a fairly inefficient fashion, is consuming huge amounts of memory unnecessarily and, on large data sets, is likely to crash with the error:

```
fatal error: out of memory
```

## summary of test results

stddev() crashing on large data sets is probably just a symptom. I suspect the underlying problem will be found in the algorithm being used to calculate standard deviations. To try to demonstrate that "something strange is going on", I needed comparison tests. I used SQLite because it was handy, I was familiar with it, I already had it running on the same hardware, I could easily load the same data into it, and both it and Influx implement roughly similar 4GL front ends. It was simply the closest I could get to a reasonably fair basis for meaningful comparison. *Nothing else should be read into this choice.*

In the table below, each "Test Group" refers to the size of the result set (ie the value to the right of "count" in each grouping). See [influx\_tests](#influxTests) for an expanded definition.

The original voltage data is recorded to 1 decimal place. Results in this table are rounded to two decimal places.

![Timings](https://user-images.githubusercontent.com/34226495/111106704-b6562c00-85a9-11eb-8a62-9e62ebf1466a.png)

> † The overall elapsed time for running the Influx test suite was 4:49.47. Subtracting the other values in the "Influx Time" column leaves 0:32.06 which implies the stddev() function crashed approximately 32 seconds into the calculation.

Definition of values in "Test" column:

* *count:* count(), both Influx and SQLite
* *mean:* Influx mean(), SQLite avg()
* *sumSq:* sum(voltage*voltage), indirect via sub-select for Influx, direct for SQLite
* *calc:* inline calculation of sample standard deviation using this formula:

![sampleStandardDeviationComputational](https://user-images.githubusercontent.com/34226495/111105319-ce787c00-85a6-11eb-872d-2c3e6cd42269.png)

* *stddev:* Influx stddev() function (no equivalent for SQLite)

Key points:

1. The [raw test results](#rawTestResults) show that the inline calculations and stddev() functions return the same answer (to many more decimal places). In other words, there is no evidence to conclude that either the inline calculation or stddev() function is either "incorrect" or "more correct" in any mathematical or statistical sense.
2. The fact that the inline calculation can compute the standard deviation for the 9M records case, while the stddev() function crashes, shows that there is no particular reason why stddev() should need a lot of memory.
3. The "calc" rows show *significant* timing differences between Influx and SQLite. For example, 84 seconds for Influx vs 4 seconds for SQLite, to calculate the 9M records case. This is due to a minor implementation difference and was a conscious choice to make the point as to how the stddev() function was likely to be going about its work. There is more detail at [inline calculation differences](#inlineCalculations).

The following chart displays the timing results from the above table:

![stddev-results](https://user-images.githubusercontent.com/34226495/111105362-ea7c1d80-85a6-11eb-9ae6-b3d15293bca1.png)

Key points:

1. The similarity of Influx timings for "Influx sumSq", "Influx calc" and "Influx stddev" lines at n=1K and n=3M, seem to suggest that the stddev() function is employing a similar tactic of calculating the sum of squares as a separate pass over the data. There is every reason to think that, were sufficient memory to be thrown at the process such that it did not crash the container, the "Influx stddev" line would have similar scale to "Influx calc" at n=9M.
2. The clustering of almost-horizontal dotted lines showing the SQLite comparisons demonstrates that there is no particular reason why standard deviations can't be calculated efficiently in a single pass. Something like the following pesudo-code:

```
function sampleStandardDeviation(data) {

n = 0
sumx = 0.0
sumxx = 0.0

while not eof(data) do {
value = nextValue(data)
n = n + 1
sumx = sumx + value
sumxx = sumxx + value * value
}

if (n > 1) {
sampleVariance = (sumxx - power(sumx/n,2) * n) / (n - 1)
return sqrt(variance)
}

return NAN

}
```

I have included:

- Three [test scripts](#testScripts)

- [`run_standard_deviation_tests`](#runStandardDeviationTests)
- [`influx_tests`](#influxTests)
- [`sqlite_tests`](#sqliteTests)

- Links to two [data sets](#testData)

- [InfluxDB data (line format)](#influxdbTestData)
- [SQLite database (pre-loaded)](#sqliteTestData)

- [Raw test results](#rawTestResults) including a complete `docker logs influxdb` for the crash.

All data was loaded onto, and all tests were performed on, a separate test 4GB Raspberry Pi 4B with a 500MB SSD that was not ingesting live data. InfluxDB was the only Docker container running.

## test scripts

### bash script: run\_standard\_deviation\_tests

This is a "master" script which:

* brings up the InfluxDB container
* installs the `time` routine inside the container so that operations can be timed
* copies the [`influx_tests`](#influxTests) script into the container
* executes the [`influx_tests`](#influxTests) script inside the container
* assumes the container crashed on the final test, waits for the container to auto-restart ('restart: unless-stopped') then captures the container log
* takes down the container
* executes the [`sqlite_tests`](#sqliteTests) script.

```
#!/bin/bash

# document the system under test
uname -a

# bring up the InfluxDB container
docker-compose -f ~/IOTstack/docker-compose.yml up -d influxdb

# wait for it to be ready
while ! nc -w 1 127.0.0.1 8086 ; do echo "waiting for Influx on port 8086"; sleep 1; done

# install time routine inside container
docker exec influxdb bash -c "apt update; apt install -y time"

# copy test script into place
docker cp ~/influx_tests influxdb:/influx_tests

# run the influx tests
/usr/bin/time -f "Wallclock: %E\n" docker exec influxdb /influx_tests

# the container crashes on the last test. Wait for it to restart.
while ! nc -w 1 127.0.0.1 8086 ; do echo "waiting for Influx on port 8086"; sleep 1; done

# fetch the logs
docker logs influxdb

# terminate the container
docker-compose -f ~/IOTstack/docker-compose.yml stop influxdb
docker-compose -f ~/IOTstack/docker-compose.yml rm -f influxdb

# run the SQLite comparisons
~/sqlite_tests
```

### bash script: influx\_tests

This script contains three test groupings, varying according to the number of records in the result set:

* exactly 1,000 records
* approximately 3 million records (roughly a year of data)
* all records in the series (around 9 million, representing 3 years' data).

Within each grouping, there are five tests:

* computes the number of records (n)
* computes the mean voltage
* computes the voltage sum of squares
* computes the sample standard deviation as an inline calculation
* computes the sample standard deviation using the stddev() function

```
#!/bin/bash

echo -e "\n\n\nInfluxDB Test Group 1 (1000 records)"
/usr/bin/time -f "Wallclock: %E\n" influx -precision=rfc3339 -database power -execute "select count(voltage) from hiking where time > 1615585930509531238"
/usr/bin/time -f "Wallclock: %E\n" influx -precision=rfc3339 -database power -execute "select mean(voltage) from hiking where time > 1615585930509531238"
/usr/bin/time -f "Wallclock: %E\n" influx -precision=rfc3339 -database power -execute "select sum(voltagesquared) from (select pow(voltage,2) as voltagesquared from hiking where time > 1615585930509531238)"
/usr/bin/time -f "Wallclock: %E\n" influx -precision=rfc3339 -database power -execute "select sqrt((sum(voltagesquared) - pow(mean(voltage),2) * count(voltage)) / (count(voltage) - 1)) from hiking, (select pow(voltage,2) as voltagesquared from hiking) where time > 1615585930509531238"
/usr/bin/time -f "Wallclock: %E\n" influx -precision=rfc3339 -database power -execute "select stddev(voltage) from hiking where time > 1615585930509531238"

echo -e "\n\n\nInfluxDB Test Group 2 (approximately a year of records)"
/usr/bin/time -f "Wallclock: %E\n" influx -precision=rfc3339 -database power -execute "select count(voltage) from hiking where time >= 1584018002822555618"
/usr/bin/time -f "Wallclock: %E\n" influx -precision=rfc3339 -database power -execute "select mean(voltage) from hiking where time >= 1584018002822555618"
/usr/bin/time -f "Wallclock: %E\n" influx -precision=rfc3339 -database power -execute "select sum(voltagesquared) from (select pow(voltage,2) as voltagesquared from hiking where time >= 1584018002822555618)"
/usr/bin/time -f "Wallclock: %E\n" influx -precision=rfc3339 -database power -execute "select sqrt((sum(voltagesquared) - pow(mean(voltage),2) * count(voltage)) / (count(voltage) - 1)) from hiking, (select pow(voltage,2) as voltagesquared from hiking) where time >= 1584018002822555618"
/usr/bin/time -f "Wallclock: %E\n" influx -precision=rfc3339 -database power -execute "select stddev(voltage) from hiking where time >= 1584018002822555618"

echo -e "\n\n\nInfluxDB Test Group 3 (all records)"
/usr/bin/time -f "Wallclock: %E\n" influx -precision=rfc3339 -database power -execute "select count(voltage) from hiking"
/usr/bin/time -f "Wallclock: %E\n" influx -precision=rfc3339 -database power -execute "select mean(voltage) from hiking"
/usr/bin/time -f "Wallclock: %E\n" influx -precision=rfc3339 -database power -execute "select sum(voltagesquared) from (select pow(voltage,2) as voltagesquared from hiking)"
/usr/bin/time -f "Wallclock: %E\n" influx -precision=rfc3339 -database power -execute "select sqrt((sum(voltagesquared) - pow(mean(voltage),2) * count(voltage)) / (count(voltage) - 1)) from hiking, (select pow(voltage,2) as voltagesquared from hiking)"
/usr/bin/time -f "Wallclock: %E\n" influx -precision=rfc3339 -database power -execute "select stddev(voltage) from hiking"

```

### bash script: sqlite\_tests

This contains the same three test groupings as [influx\_tests](#influxTests), varying according to the number of records in the result set (1000, 3 million, 9 million records), but only four tests per grouping:

* computes the number of records (n)
* computes the mean voltage
* computes the voltage sum of squares
* computes the sample standard deviation as an inline calculation

SQLite does not have a built-in stddev() function so that test is omitted.

```
#!/bin/bash

echo -e "\n\n\nSQLite Test Group 1 (1000 records)"
/usr/bin/time -f "Wallclock: %E\n" sqlite3 -column -header power.db 'select count(voltage) from hiking where time > 1615585930509531238;'
/usr/bin/time -f "Wallclock: %E\n" sqlite3 -column -header power.db 'select avg(voltage) from hiking where time > 1615585930509531238;'
/usr/bin/time -f "Wallclock: %E\n" sqlite3 -column -header power.db 'select sum(pow(voltage,2)) from hiking where time > 1615585930509531238;'
/usr/bin/time -f "Wallclock: %E\n" sqlite3 -column -header power.db 'select sqrt((sumxx - pow(sumx/n,2) * n) / (n - 1)) as stddev from (select count(voltage) as n, sum(voltage) as sumx, sum(pow(voltage,2)) as sumxx from hiking where time > 1615585930509531238);'

echo -e "\n\n\nSQLite Test Group 2 (approximately a year of records)"
/usr/bin/time -f "Wallclock: %E\n" sqlite3 -column -header power.db 'select count(voltage) from hiking where time >= 1584018002822555618;'
/usr/bin/time -f "Wallclock: %E\n" sqlite3 -column -header power.db 'select avg(voltage) from hiking where time >= 1584018002822555618;'
/usr/bin/time -f "Wallclock: %E\n" sqlite3 -column -header power.db 'select sum(pow(voltage,2)) from hiking where time >= 1584018002822555618;'
/usr/bin/time -f "Wallclock: %E\n" sqlite3 -column -header power.db 'select sqrt((sumxx - pow(sumx/n,2) * n) / (n - 1)) as stddev from (select count(voltage) as n, sum(voltage) as sumx, sum(pow(voltage,2)) as sumxx from hiking where time >= 1584018002822555618);'

echo -e "\n\n\nSQLite Test Group (all records)"
/usr/bin/time -f "Wallclock: %E\n" sqlite3 -column -header power.db 'select count(voltage) from hiking;'
/usr/bin/time -f "Wallclock: %E\n" sqlite3 -column -header power.db 'select avg(voltage) from hiking;'
/usr/bin/time -f "Wallclock: %E\n" sqlite3 -column -header power.db 'select sum(pow(voltage,2)) from hiking;'
/usr/bin/time -f "Wallclock: %E\n" sqlite3 -column -header power.db 'select sqrt((sumxx - pow(sumx/n,2) * n) / (n - 1)) as stddev from (select count(voltage) as n, sum(voltage) as sumx, sum(pow(voltage,2)) as sumxx from hiking);'
```

### inline calculation differences

There are structural differences between the Influx and SQLite inline calculations. The differences are deliberate and intended to highlight the fact that:

* the timing of Influx's stddev() is closer to what happens if the sum of squares is calculated separately:

```
select sqrt((sum(voltagesquared) - pow(mean(voltage),2) * count(voltage)) / (count(voltage) - 1)) from hiking, (select pow(voltage,2) as voltagesquared from hiking)
```

* the implementation approach for SQLite is closer to the pseudo-code given earlier (accumulate n, ∑x and ∑x² in a single pass, then compute the answer):

```
select sqrt((sumxx - pow(sumx/n,2) * n) / (n - 1)) as stddev from (select count(voltage) as n, sum(voltage) as sumx, sum(pow(voltage,2)) as sumxx from hiking);
```

## test data

### InfluxDB data (line format)

I erased the Docker persistent storage area for InfluxDB then let the container re-initialise.

The test data is in line-format:

* [hiking-voltage-import.txt.zip (Dropbox, 69MB](https://www.dropbox.com/s/g2fz6nsedyn5vnx/hiking-voltage-import.txt.zip?dl=1). I put this file on Dropbox because I did not know whether GitHub would welcome a 69MB attachment but I am happy to attach it to this issue on request.

* I loaded it like this:

```
$ docker exec influxdb influx -import -path=hiking-voltage-import.txt
```

Sample:

```
$ docker exec -it influxdb influx -precision=rfc3339
Connected to http://localhost:8086 version 1.8.4
InfluxDB shell version: 1.8.4

> show databases
name: databases
name
----
power

> use power
Using database power

> show series
key
---
hiking

> show measurements
name: measurements
name
----
hiking

> select * from hiking limit 5
name: hiking
time voltage
---- -------
2018-04-10T14:00:06.663Z 247.4
2018-04-10T14:00:16.662Z 247.4
2018-04-10T14:00:26.665Z 247.6
2018-04-10T14:00:36.662Z 247.5
2018-04-10T14:00:46.663Z 247.6
```

### SQLite database (pre-loaded)

Identical test data (already loaded into an SQLite DB):

* [power.db.zip (Dropbox, 102MB)](https://www.dropbox.com/s/py8wru57f0kgqz2/power.db.zip?dl=1). As with the Influx test data, I put the file on Dropbox because I did not know whether GitHub would welcome a 102MB attachment but I am happy to attach it to this issue on request.

Sample:

```
$ sqlite3 -column -header power.db
SQLite version 3.35.0 2021-03-12 15:10:09
Enter ".help" for usage hints.

sqlite> .tables
hiking

sqlite> select * from hiking limit 5;
voltage time
------- -------------------
247.4 1523368806663000000
247.4 1523368816662000000
247.6 1523368826665000000
247.5 1523368836662000000
247.6 1523368846663000000
```

## raw test results

```
$ ./run_standard_deviation_tests
Linux sec-dev 5.10.17-v7l+ #1403 SMP Mon Feb 22 11:33:35 GMT 2021 armv7l GNU/Linux

Creating influxdb ... done

WARNING: apt does not have a stable CLI interface. Use with caution in scripts.

Ign:1 http://deb.debian.org/debian stretch InRelease
Get:2 http://security.debian.org/debian-security stretch/updates InRelease [53.0 kB]
Get:3 http://deb.debian.org/debian stretch-updates InRelease [93.6 kB]
Get:4 http://deb.debian.org/debian stretch Release [118 kB]
Get:5 http://deb.debian.org/debian stretch Release.gpg [2410 B]
Get:6 http://security.debian.org/debian-security stretch/updates/main armhf Packages [637 kB]
Get:7 http://deb.debian.org/debian stretch-updates/main armhf Packages [2576 B]
Get:8 http://deb.debian.org/debian stretch/main armhf Packages [6908 kB]
Fetched 7816 kB in 4s (1699 kB/s)
Reading package lists...
Building dependency tree...
Reading state information...
1 package can be upgraded. Run 'apt list --upgradable' to see it.

WARNING: apt does not have a stable CLI interface. Use with caution in scripts.

Reading package lists...
Building dependency tree...
Reading state information...
The following NEW packages will be installed:
time
0 upgraded, 1 newly installed, 0 to remove and 1 not upgraded.
Need to get 31.6 kB of archives.
After this operation, 82.9 kB of additional disk space will be used.
Get:1 http://deb.debian.org/debian stretch/main armhf time armhf 1.7-25.1+b1 [31.6 kB]
debconf: delaying package configuration, since apt-utils is not installed
Fetched 31.6 kB in 0s (680 kB/s)
Selecting previously unselected package time.
(Reading database ... 7291 files and directories currently installed.)
Preparing to unpack .../time_1.7-25.1+b1_armhf.deb ...
Unpacking time (1.7-25.1+b1) ...
Setting up time (1.7-25.1+b1) ...

InfluxDB Test Group 1 (1000 records)
name: hiking
time count
---- -----
2021-03-12T21:52:10.509531239Z 1000
Wallclock: 0:00.05

name: hiking
time mean
---- ----
2021-03-12T21:52:10.509531239Z 249.93519999999992
Wallclock: 0:00.04

name: hiking
time sum
---- ---
1970-01-01T00:00:00Z 62475887.760000005
Wallclock: 0:00.05

name: hiking
time sqrt
---- ----
2021-03-12T21:52:10.509531239Z 2.879557746054037
Wallclock: 0:00.04

name: hiking
time stddev
---- ------
2021-03-12T21:52:10.509531239Z 2.8795577460458768
Wallclock: 0:00.04

InfluxDB Test Group 2 (approximately a year of records)
name: hiking
time count
---- -----
2020-03-12T13:00:02.822555618Z 3163157
Wallclock: 0:01.41

name: hiking
time mean
---- ----
2020-03-12T13:00:02.822555618Z 246.49246060186093
Wallclock: 0:01.83

name: hiking
time sum
---- ---
1970-01-01T00:00:00Z 192211041158.78198
Wallclock: 0:26.16

name: hiking
time sqrt
---- ----
2020-03-12T13:00:02.822555618Z 2.6528916767192796
Wallclock: 0:29.34

name: hiking
time stddev
---- ------
2020-03-12T13:00:02.822555618Z 2.6528916766214747
Wallclock: 0:29.88

InfluxDB Test Group 3 (all records)
name: hiking
time count
---- -----
1970-01-01T00:00:00Z 9150127
Wallclock: 0:03.76

name: hiking
time mean
---- ----
1970-01-01T00:00:00Z 246.2617303563109
Wallclock: 0:04.90

name: hiking
time sum
---- ---
1970-01-01T00:00:00Z 554970818683.4951
Wallclock: 1:16.24

name: hiking
time sqrt
---- ----
1970-01-01T00:00:00Z 2.620461836079568
Wallclock: 1:24.47

ERR: no data received
no data received
Command exited with non-zero status 137
Wallclock: 4:49.47

ts=2021-03-14T11:15:19.774850Z lvl=info msg="InfluxDB starting" log_id=0St6~xNW000 version=1.8.4 branch=1.8 commit=bc8ec4384eed25436d31045f974bf39f3310fa3c
ts=2021-03-14T11:15:19.774964Z lvl=info msg="Go runtime" log_id=0St6~xNW000 version=go1.13.8 maxprocs=4
ts=2021-03-14T11:15:19.877376Z lvl=info msg="Using data dir" log_id=0St6~xNW000 service=store path=/var/lib/influxdb/data
ts=2021-03-14T11:15:19.877511Z lvl=info msg="Compaction settings" log_id=0St6~xNW000 service=store max_concurrent_compactions=2 throughput_bytes_per_second=50331648 throughput_bytes_per_second_burst=50331648
ts=2021-03-14T11:15:19.877603Z lvl=info msg="Open store (start)" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open op_event=start
ts=2021-03-14T11:15:19.890808Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/99/000000001-000000001.tsm id=0 duration=0.557ms
ts=2021-03-14T11:15:19.890866Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/109/000000001-000000001.tsm id=0 duration=0.798ms
ts=2021-03-14T11:15:19.892392Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/104/000000001-000000001.tsm id=0 duration=0.238ms
ts=2021-03-14T11:15:19.893269Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/107/000000001-000000001.tsm id=0 duration=1.040ms
ts=2021-03-14T11:15:19.902848Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/99 duration=18.535ms
ts=2021-03-14T11:15:19.904829Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/109 duration=22.212ms
ts=2021-03-14T11:15:19.904924Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/104 duration=22.499ms
ts=2021-03-14T11:15:19.904716Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/11/000000001-000000001.tsm id=0 duration=0.194ms
ts=2021-03-14T11:15:19.906774Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/110/000000001-000000001.tsm id=0 duration=0.257ms
ts=2021-03-14T11:15:19.906697Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/111/000000001-000000001.tsm id=0 duration=0.229ms
ts=2021-03-14T11:15:19.906872Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/107 duration=24.268ms
ts=2021-03-14T11:15:19.909585Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/112/000000001-000000001.tsm id=0 duration=0.211ms
ts=2021-03-14T11:15:19.916344Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/11 duration=13.250ms
ts=2021-03-14T11:15:19.918308Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/110 duration=13.205ms
ts=2021-03-14T11:15:19.918364Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/111 duration=13.174ms
ts=2021-03-14T11:15:19.918174Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/113/000000001-000000001.tsm id=0 duration=0.194ms
ts=2021-03-14T11:15:19.923029Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/114/000000001-000000001.tsm id=0 duration=0.540ms
ts=2021-03-14T11:15:19.923591Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/112 duration=16.095ms
ts=2021-03-14T11:15:19.925333Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/115/000000001-000000001.tsm id=0 duration=0.387ms
ts=2021-03-14T11:15:19.927770Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/116/000000001-000000001.tsm id=0 duration=1.227ms
ts=2021-03-14T11:15:19.933066Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/113 duration=16.499ms
ts=2021-03-14T11:15:19.934883Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/117/000000001-000000001.tsm id=0 duration=0.190ms
ts=2021-03-14T11:15:19.935346Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/114 duration=16.812ms
ts=2021-03-14T11:15:19.937031Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/118/000000001-000000001.tsm id=0 duration=0.223ms
ts=2021-03-14T11:15:19.941123Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/115 duration=22.337ms
ts=2021-03-14T11:15:19.941380Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/116 duration=17.679ms
ts=2021-03-14T11:15:19.942701Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/119/000000001-000000001.tsm id=0 duration=0.196ms
ts=2021-03-14T11:15:19.942755Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/12/000000001-000000001.tsm id=0 duration=0.161ms
ts=2021-03-14T11:15:19.948773Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/118 duration=13.144ms
ts=2021-03-14T11:15:19.948824Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/117 duration=15.656ms
ts=2021-03-14T11:15:19.952635Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/120/000000001-000000001.tsm id=0 duration=2.502ms
ts=2021-03-14T11:15:19.954234Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/121/000000001-000000001.tsm id=0 duration=0.568ms
ts=2021-03-14T11:15:19.955622Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/12 duration=14.157ms
ts=2021-03-14T11:15:19.957934Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/122/000000001-000000001.tsm id=0 duration=0.199ms
ts=2021-03-14T11:15:19.959315Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/119 duration=18.071ms
ts=2021-03-14T11:15:19.961416Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/123/000000001-000000001.tsm id=0 duration=0.261ms
ts=2021-03-14T11:15:19.965721Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/120 duration=16.794ms
ts=2021-03-14T11:15:19.967497Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/121 duration=18.154ms
ts=2021-03-14T11:15:19.967446Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/124/000000001-000000001.tsm id=0 duration=0.255ms
ts=2021-03-14T11:15:19.969254Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/125/000000001-000000001.tsm id=0 duration=0.289ms
ts=2021-03-14T11:15:19.972073Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/122 duration=16.314ms
ts=2021-03-14T11:15:19.973856Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/126/000000001-000000001.tsm id=0 duration=0.223ms
ts=2021-03-14T11:15:19.974283Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/123 duration=14.701ms
ts=2021-03-14T11:15:19.975963Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/127/000000001-000000001.tsm id=0 duration=0.470ms
ts=2021-03-14T11:15:19.982449Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/125 duration=14.781ms
ts=2021-03-14T11:15:19.984413Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/128/000000001-000000001.tsm id=0 duration=0.335ms
ts=2021-03-14T11:15:19.984945Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/126 duration=12.775ms
ts=2021-03-14T11:15:19.986841Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/129/000000001-000000001.tsm id=0 duration=0.212ms
ts=2021-03-14T11:15:19.988362Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/124 duration=22.306ms
ts=2021-03-14T11:15:19.993494Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/127 duration=19.125ms
ts=2021-03-14T11:15:19.993550Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/13/000000001-000000001.tsm id=0 duration=0.316ms
ts=2021-03-14T11:15:19.995112Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/130/000000001-000000001.tsm id=0 duration=0.194ms
ts=2021-03-14T11:15:20.000797Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/128 duration=17.953ms
ts=2021-03-14T11:15:20.002886Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/131/000000001-000000001.tsm id=0 duration=0.191ms
ts=2021-03-14T11:15:20.003180Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/129 duration=18.135ms
ts=2021-03-14T11:15:20.004775Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/132/000000001-000000001.tsm id=0 duration=0.194ms
ts=2021-03-14T11:15:20.005897Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/13 duration=17.346ms
ts=2021-03-14T11:15:20.007762Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/130 duration=14.153ms
ts=2021-03-14T11:15:20.007983Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/133/000000001-000000001.tsm id=0 duration=0.393ms
ts=2021-03-14T11:15:20.015602Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/134/000000001-000000001.tsm id=0 duration=5.600ms
ts=2021-03-14T11:15:20.016356Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/131 duration=15.460ms
ts=2021-03-14T11:15:20.017613Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/132 duration=14.270ms
ts=2021-03-14T11:15:20.018917Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/135/000000001-000000001.tsm id=0 duration=0.189ms
ts=2021-03-14T11:15:20.020084Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/136/000000001-000000001.tsm id=0 duration=0.272ms
ts=2021-03-14T11:15:20.022119Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/133 duration=16.130ms
ts=2021-03-14T11:15:20.023834Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/137/000000001-000000001.tsm id=0 duration=0.187ms
ts=2021-03-14T11:15:20.027876Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/134 duration=20.021ms
ts=2021-03-14T11:15:20.029928Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/135 duration=12.748ms
ts=2021-03-14T11:15:20.029856Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/138/000000001-000000001.tsm id=0 duration=0.260ms
ts=2021-03-14T11:15:20.031877Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/136 duration=14.170ms
ts=2021-03-14T11:15:20.031666Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/139/000000001-000000001.tsm id=0 duration=0.192ms
ts=2021-03-14T11:15:20.033483Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/14/000000001-000000001.tsm id=0 duration=0.191ms
ts=2021-03-14T11:15:20.037553Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/137 duration=15.331ms
ts=2021-03-14T11:15:20.041490Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/140/000000001-000000001.tsm id=0 duration=0.199ms
ts=2021-03-14T11:15:20.042717Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/139 duration=12.710ms
ts=2021-03-14T11:15:20.042804Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/138 duration=14.701ms
ts=2021-03-14T11:15:20.044522Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/141/000000001-000000001.tsm id=0 duration=0.214ms
ts=2021-03-14T11:15:20.044575Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/142/000000001-000000001.tsm id=0 duration=0.208ms
ts=2021-03-14T11:15:20.048924Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/14 duration=16.962ms
ts=2021-03-14T11:15:20.050468Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/143/000000001-000000001.tsm id=0 duration=0.194ms
ts=2021-03-14T11:15:20.054858Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/140 duration=17.159ms
ts=2021-03-14T11:15:20.056756Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/141 duration=13.949ms
ts=2021-03-14T11:15:20.057129Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/142 duration=13.919ms
ts=2021-03-14T11:15:20.056652Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/144/000000001-000000001.tsm id=0 duration=0.205ms
ts=2021-03-14T11:15:20.058369Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/145/000000001-000000001.tsm id=0 duration=0.191ms
ts=2021-03-14T11:15:20.059383Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/146/000000001-000000001.tsm id=0 duration=0.253ms
ts=2021-03-14T11:15:20.062766Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/143 duration=13.646ms
ts=2021-03-14T11:15:20.065521Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/147/000000001-000000001.tsm id=0 duration=0.768ms
ts=2021-03-14T11:15:20.072107Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/145 duration=15.270ms
ts=2021-03-14T11:15:20.073903Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/148/000000001-000000001.tsm id=0 duration=0.221ms
ts=2021-03-14T11:15:20.073982Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/146 duration=16.753ms
ts=2021-03-14T11:15:20.075666Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/149/000000001-000000001.tsm id=0 duration=0.192ms
ts=2021-03-14T11:15:20.076671Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/144 duration=21.712ms
ts=2021-03-14T11:15:20.078473Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/15/000000001-000000001.tsm id=0 duration=0.187ms
ts=2021-03-14T11:15:20.078792Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/147 duration=15.815ms
ts=2021-03-14T11:15:20.080304Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/150/000000001-000000001.tsm id=0 duration=0.194ms
ts=2021-03-14T11:15:20.086212Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/148 duration=13.975ms
ts=2021-03-14T11:15:20.087984Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/151/000000001-000000001.tsm id=0 duration=0.217ms
ts=2021-03-14T11:15:20.088176Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/149 duration=13.995ms
ts=2021-03-14T11:15:20.090060Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/152/000000001-000000001.tsm id=0 duration=0.242ms
ts=2021-03-14T11:15:20.090130Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/15 duration=13.313ms
ts=2021-03-14T11:15:20.092028Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/153/000000001-000000001.tsm id=0 duration=0.288ms
ts=2021-03-14T11:15:20.092272Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/150 duration=13.297ms
ts=2021-03-14T11:15:20.094313Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/16/000000001-000000001.tsm id=0 duration=0.396ms
ts=2021-03-14T11:15:20.103281Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/152 duration=15.015ms
ts=2021-03-14T11:15:20.104258Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/151 duration=17.927ms
ts=2021-03-14T11:15:20.105277Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/17/000000001-000000001.tsm id=0 duration=0.336ms
ts=2021-03-14T11:15:20.106014Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/18/000000001-000000001.tsm id=0 duration=0.376ms
ts=2021-03-14T11:15:20.106449Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/153 duration=15.974ms
ts=2021-03-14T11:15:20.107907Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/16 duration=15.412ms
ts=2021-03-14T11:15:20.110477Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/19/000000001-000000001.tsm id=0 duration=0.246ms
ts=2021-03-14T11:15:20.109902Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/2/000000001-000000001.tsm id=0 duration=0.335ms
ts=2021-03-14T11:15:20.116370Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/17 duration=12.922ms
ts=2021-03-14T11:15:20.116502Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/18 duration=11.977ms
ts=2021-03-14T11:15:20.117891Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/20/000000001-000000001.tsm id=0 duration=0.205ms
ts=2021-03-14T11:15:20.117889Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/21/000000001-000000001.tsm id=0 duration=0.204ms
ts=2021-03-14T11:15:20.120814Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/2 duration=12.675ms
ts=2021-03-14T11:15:20.121032Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/19 duration=14.500ms
ts=2021-03-14T11:15:20.125699Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/22/000000001-000000001.tsm id=0 duration=3.489ms
ts=2021-03-14T11:15:20.126979Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/23/000000001-000000001.tsm id=0 duration=1.218ms
ts=2021-03-14T11:15:20.133191Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/20 duration=16.677ms
ts=2021-03-14T11:15:20.134870Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/24/000000001-000000001.tsm id=0 duration=0.189ms
ts=2021-03-14T11:15:20.135051Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/21 duration=18.478ms
ts=2021-03-14T11:15:20.136918Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/25/000000001-000000001.tsm id=0 duration=0.192ms
ts=2021-03-14T11:15:20.141127Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/23 duration=19.865ms
ts=2021-03-14T11:15:20.142972Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/26/000000001-000000001.tsm id=0 duration=0.196ms
ts=2021-03-14T11:15:20.143397Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/22 duration=22.489ms
ts=2021-03-14T11:15:20.145923Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/27/000000001-000000001.tsm id=0 duration=0.189ms
ts=2021-03-14T11:15:20.147261Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/24 duration=13.843ms
ts=2021-03-14T11:15:20.149032Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/28/000000001-000000001.tsm id=0 duration=0.209ms
ts=2021-03-14T11:15:20.149356Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/25 duration=13.995ms
ts=2021-03-14T11:15:20.150849Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/29/000000001-000000001.tsm id=0 duration=0.182ms
ts=2021-03-14T11:15:20.156750Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/26 duration=15.532ms
ts=2021-03-14T11:15:20.158427Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/3/000000001-000000001.tsm id=0 duration=0.187ms
ts=2021-03-14T11:15:20.158841Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/27 duration=15.334ms
ts=2021-03-14T11:15:20.160501Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/30/000000001-000000001.tsm id=0 duration=0.247ms
ts=2021-03-14T11:15:20.160961Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/28 duration=13.566ms
ts=2021-03-14T11:15:20.165409Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/29 duration=15.920ms
ts=2021-03-14T11:15:20.166651Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/31/000000001-000000001.tsm id=0 duration=0.565ms
ts=2021-03-14T11:15:20.169421Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/32/000000001-000000001.tsm id=0 duration=0.205ms
ts=2021-03-14T11:15:20.177537Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/3 duration=20.660ms
ts=2021-03-14T11:15:20.177619Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/31 duration=16.365ms
ts=2021-03-14T11:15:20.179310Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/34/000000001-000000001.tsm id=0 duration=0.211ms
ts=2021-03-14T11:15:20.179495Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/33/000000001-000000001.tsm id=0 duration=0.396ms
ts=2021-03-14T11:15:20.179421Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/30 duration=20.410ms
ts=2021-03-14T11:15:20.181998Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/35/000000001-000000001.tsm id=0 duration=0.226ms
ts=2021-03-14T11:15:20.182111Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/32 duration=16.583ms
ts=2021-03-14T11:15:20.183966Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/36/000000001-000000001.tsm id=0 duration=0.246ms
ts=2021-03-14T11:15:20.191350Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/33 duration=13.699ms
ts=2021-03-14T11:15:20.191462Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/34 duration=13.553ms
ts=2021-03-14T11:15:20.193210Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/37/000000001-000000001.tsm id=0 duration=0.201ms
ts=2021-03-14T11:15:20.193628Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/35 duration=13.684ms
ts=2021-03-14T11:15:20.193263Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/38/000000001-000000001.tsm id=0 duration=0.257ms
ts=2021-03-14T11:15:20.195963Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/36 duration=13.501ms
ts=2021-03-14T11:15:20.196269Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/39/000000001-000000001.tsm id=0 duration=0.487ms
ts=2021-03-14T11:15:20.197844Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/4/000000001-000000001.tsm id=0 duration=0.199ms
ts=2021-03-14T11:15:20.206219Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/37 duration=14.566ms
ts=2021-03-14T11:15:20.207940Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/40/000000001-000000001.tsm id=0 duration=0.196ms
ts=2021-03-14T11:15:20.208490Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/38 duration=16.815ms
ts=2021-03-14T11:15:20.212893Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/39 duration=19.176ms
ts=2021-03-14T11:15:20.214299Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/4 duration=18.106ms
ts=2021-03-14T11:15:20.216106Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/42/000000001-000000001.tsm id=0 duration=0.211ms
ts=2021-03-14T11:15:20.216467Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/41/000000001-000000001.tsm id=0 duration=2.330ms
ts=2021-03-14T11:15:20.217209Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/43/000000001-000000001.tsm id=0 duration=0.626ms
ts=2021-03-14T11:15:20.220402Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/40 duration=13.845ms
ts=2021-03-14T11:15:20.222178Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/44/000000001-000000001.tsm id=0 duration=0.195ms
ts=2021-03-14T11:15:20.227877Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/42 duration=14.715ms
ts=2021-03-14T11:15:20.227971Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/41 duration=19.052ms
ts=2021-03-14T11:15:20.229655Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/45/000000001-000000001.tsm id=0 duration=0.208ms
ts=2021-03-14T11:15:20.229661Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/46/000000001-000000001.tsm id=0 duration=0.215ms
ts=2021-03-14T11:15:20.229763Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/43 duration=15.339ms
ts=2021-03-14T11:15:20.232467Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/44 duration=11.871ms
ts=2021-03-14T11:15:20.232533Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/47/000000001-000000001.tsm id=0 duration=0.287ms
ts=2021-03-14T11:15:20.234231Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/48/000000001-000000001.tsm id=0 duration=0.201ms
ts=2021-03-14T11:15:20.240682Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/45 duration=12.549ms
ts=2021-03-14T11:15:20.240799Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/46 duration=12.695ms
ts=2021-03-14T11:15:20.242269Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/49/000000001-000000001.tsm id=0 duration=0.202ms
ts=2021-03-14T11:15:20.242311Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/5/000000001-000000001.tsm id=0 duration=0.167ms
ts=2021-03-14T11:15:20.245158Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/47 duration=14.710ms
ts=2021-03-14T11:15:20.247182Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/48 duration=14.530ms
ts=2021-03-14T11:15:20.247037Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/50/000000001-000000001.tsm id=0 duration=0.212ms
ts=2021-03-14T11:15:20.249067Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/51/000000001-000000001.tsm id=0 duration=0.183ms
ts=2021-03-14T11:15:20.251981Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/49 duration=11.047ms
ts=2021-03-14T11:15:20.251963Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/5 duration=10.999ms
ts=2021-03-14T11:15:20.255772Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/53/000000001-000000001.tsm id=0 duration=2.387ms
ts=2021-03-14T11:15:20.260554Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/52/000000001-000000001.tsm id=0 duration=7.193ms
ts=2021-03-14T11:15:20.261917Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/51 duration=14.659ms
ts=2021-03-14T11:15:20.263845Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/50 duration=18.365ms
ts=2021-03-14T11:15:20.264197Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/108/000000001-000000001.tsm id=0 duration=0.703ms
ts=2021-03-14T11:15:20.265745Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/1/000000001-000000001.tsm id=0 duration=0.262ms
ts=2021-03-14T11:15:20.270164Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/53 duration=17.917ms
ts=2021-03-14T11:15:20.271998Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/105/000000001-000000001.tsm id=0 duration=0.338ms
ts=2021-03-14T11:15:20.272094Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/52 duration=19.879ms
ts=2021-03-14T11:15:20.273753Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/106/000000001-000000001.tsm id=0 duration=0.200ms
ts=2021-03-14T11:15:20.277247Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/108 duration=15.106ms
ts=2021-03-14T11:15:20.279127Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/10/000000001-000000001.tsm id=0 duration=0.214ms
ts=2021-03-14T11:15:20.279287Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/1 duration=15.057ms
ts=2021-03-14T11:15:20.280895Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/100/000000001-000000001.tsm id=0 duration=0.186ms
ts=2021-03-14T11:15:20.284974Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/105 duration=14.595ms
ts=2021-03-14T11:15:20.286709Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/76/000000001-000000001.tsm id=0 duration=0.192ms
ts=2021-03-14T11:15:20.287148Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/106 duration=14.835ms
ts=2021-03-14T11:15:20.288674Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/101/000000001-000000001.tsm id=0 duration=0.192ms
ts=2021-03-14T11:15:20.290954Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/10 duration=13.595ms
ts=2021-03-14T11:15:20.292927Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/100 duration=13.513ms
ts=2021-03-14T11:15:20.292929Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/102/000000001-000000001.tsm id=0 duration=0.343ms
ts=2021-03-14T11:15:20.294649Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/103/000000001-000000001.tsm id=0 duration=0.263ms
ts=2021-03-14T11:15:20.300874Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/101 duration=13.633ms
ts=2021-03-14T11:15:20.301153Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/76 duration=15.915ms
ts=2021-03-14T11:15:20.305856Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/87/000000001-000000001.tsm id=0 duration=0.450ms
ts=2021-03-14T11:15:20.311963Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/102 duration=20.795ms
ts=2021-03-14T11:15:20.311942Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/65/000000001-000000001.tsm id=0 duration=0.977ms
ts=2021-03-14T11:15:20.315633Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/77/000000001-000000001.tsm id=0 duration=0.305ms
ts=2021-03-14T11:15:20.316176Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/103 duration=22.892ms
ts=2021-03-14T11:15:20.318161Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/87 duration=16.741ms
ts=2021-03-14T11:15:20.318825Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/55/000000001-000000001.tsm id=0 duration=0.952ms
ts=2021-03-14T11:15:20.319984Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/66/000000001-000000001.tsm id=0 duration=0.221ms
ts=2021-03-14T11:15:20.325583Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/65 duration=24.598ms
ts=2021-03-14T11:15:20.327446Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/56/000000001-000000001.tsm id=0 duration=0.225ms
ts=2021-03-14T11:15:20.327819Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/77 duration=15.668ms
ts=2021-03-14T11:15:20.329857Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/66 duration=11.589ms
ts=2021-03-14T11:15:20.329723Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/67/000000001-000000001.tsm id=0 duration=0.199ms
ts=2021-03-14T11:15:20.331655Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/57/000000001-000000001.tsm id=0 duration=0.225ms
ts=2021-03-14T11:15:20.333817Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/55 duration=17.531ms
ts=2021-03-14T11:15:20.335487Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/68/000000001-000000001.tsm id=0 duration=0.196ms
ts=2021-03-14T11:15:20.340076Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/56 duration=14.034ms
ts=2021-03-14T11:15:20.342035Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/58/000000001-000000001.tsm id=0 duration=0.232ms
ts=2021-03-14T11:15:20.342177Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/57 duration=12.215ms
ts=2021-03-14T11:15:20.342834Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/67 duration=14.918ms
ts=2021-03-14T11:15:20.343830Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/59/000000001-000000001.tsm id=0 duration=0.208ms
ts=2021-03-14T11:15:20.348556Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/69/000000001-000000001.tsm id=0 duration=3.013ms
ts=2021-03-14T11:15:20.350491Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/68 duration=16.569ms
ts=2021-03-14T11:15:20.353084Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/7/000000001-000000001.tsm id=0 duration=0.478ms
ts=2021-03-14T11:15:20.355290Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/58 duration=15.115ms
ts=2021-03-14T11:15:20.357176Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/6/000000001-000000001.tsm id=0 duration=0.325ms
ts=2021-03-14T11:15:20.358193Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/59 duration=15.912ms
ts=2021-03-14T11:15:20.359753Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/70/000000001-000000001.tsm id=0 duration=0.203ms
ts=2021-03-14T11:15:20.361331Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/69 duration=18.403ms
ts=2021-03-14T11:15:20.363501Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/60/000000001-000000001.tsm id=0 duration=0.501ms
ts=2021-03-14T11:15:20.367680Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/7 duration=17.071ms
ts=2021-03-14T11:15:20.369477Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/71/000000001-000000001.tsm id=0 duration=0.188ms
ts=2021-03-14T11:15:20.369588Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/6 duration=14.198ms
ts=2021-03-14T11:15:20.371448Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/61/000000001-000000001.tsm id=0 duration=0.196ms
ts=2021-03-14T11:15:20.372088Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/70 duration=13.786ms
ts=2021-03-14T11:15:20.373466Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/62/000000001-000000001.tsm id=0 duration=0.189ms
ts=2021-03-14T11:15:20.377580Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/60 duration=16.102ms
ts=2021-03-14T11:15:20.379677Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/72/000000001-000000001.tsm id=0 duration=0.190ms
ts=2021-03-14T11:15:20.381715Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/71 duration=13.940ms
ts=2021-03-14T11:15:20.383600Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/73/000000001-000000001.tsm id=0 duration=0.197ms
ts=2021-03-14T11:15:20.383724Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/61 duration=14.058ms
ts=2021-03-14T11:15:20.385542Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/74/000000001-000000001.tsm id=0 duration=0.192ms
ts=2021-03-14T11:15:20.385883Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/62 duration=13.663ms
ts=2021-03-14T11:15:20.387519Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/63/000000001-000000001.tsm id=0 duration=0.192ms
ts=2021-03-14T11:15:20.392367Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/72 duration=14.469ms
ts=2021-03-14T11:15:20.394535Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/75/000000001-000000001.tsm id=0 duration=0.249ms
ts=2021-03-14T11:15:20.397126Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/73 duration=15.318ms
ts=2021-03-14T11:15:20.399570Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/64/000000001-000000001.tsm id=0 duration=0.349ms
ts=2021-03-14T11:15:20.401073Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/74 duration=17.267ms
ts=2021-03-14T11:15:20.402501Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/63 duration=16.420ms
ts=2021-03-14T11:15:20.404000Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/92/000000001-000000001.tsm id=0 duration=0.200ms
ts=2021-03-14T11:15:20.404471Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/88/000000001-000000001.tsm id=0 duration=0.420ms
ts=2021-03-14T11:15:20.410790Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/75 duration=18.170ms
ts=2021-03-14T11:15:20.412775Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/64 duration=15.226ms
ts=2021-03-14T11:15:20.412588Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/54/000000001-000000001.tsm id=0 duration=0.223ms
ts=2021-03-14T11:15:20.414833Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/93/000000001-000000001.tsm id=0 duration=0.195ms
ts=2021-03-14T11:15:20.415094Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/92 duration=13.857ms
ts=2021-03-14T11:15:20.416895Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/94/000000001-000000001.tsm id=0 duration=0.191ms
ts=2021-03-14T11:15:20.417107Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/88 duration=14.306ms
ts=2021-03-14T11:15:20.418755Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/95/000000001-000000001.tsm id=0 duration=0.185ms
ts=2021-03-14T11:15:20.424658Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/54 duration=13.643ms
ts=2021-03-14T11:15:20.426575Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/93 duration=13.601ms
ts=2021-03-14T11:15:20.427065Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/89/000000001-000000001.tsm id=0 duration=0.842ms
ts=2021-03-14T11:15:20.428416Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/91/000000001-000000001.tsm id=0 duration=0.227ms
ts=2021-03-14T11:15:20.428521Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/94 duration=13.328ms
ts=2021-03-14T11:15:20.430379Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/90/000000001-000000001.tsm id=0 duration=0.181ms
ts=2021-03-14T11:15:20.430575Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/95 duration=13.314ms
ts=2021-03-14T11:15:20.432393Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/96/000000001-000000001.tsm id=0 duration=0.211ms
ts=2021-03-14T11:15:20.440279Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/89 duration=15.332ms
ts=2021-03-14T11:15:20.440713Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/91 duration=13.732ms
ts=2021-03-14T11:15:20.442116Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/9/000000001-000000001.tsm id=0 duration=0.223ms
ts=2021-03-14T11:15:20.442115Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/78/000000001-000000001.tsm id=0 duration=0.170ms
ts=2021-03-14T11:15:20.442221Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/90 duration=13.263ms
ts=2021-03-14T11:15:20.447500Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/96 duration=16.778ms
ts=2021-03-14T11:15:20.450121Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/79/000000001-000000001.tsm id=0 duration=0.390ms
ts=2021-03-14T11:15:20.451795Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/82/000000001-000000001.tsm id=0 duration=0.219ms
ts=2021-03-14T11:15:20.456830Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/9 duration=15.940ms
ts=2021-03-14T11:15:20.458701Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/78 duration=18.313ms
ts=2021-03-14T11:15:20.458579Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/8/000000001-000000001.tsm id=0 duration=0.215ms
ts=2021-03-14T11:15:20.461082Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/80/000000001-000000001.tsm id=0 duration=0.225ms
ts=2021-03-14T11:15:20.462974Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/79 duration=15.156ms
ts=2021-03-14T11:15:20.464801Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/81/000000001-000000001.tsm id=0 duration=0.191ms
ts=2021-03-14T11:15:20.464941Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/82 duration=22.111ms
ts=2021-03-14T11:15:20.466699Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/97/000000001-000000001.tsm id=0 duration=0.211ms
ts=2021-03-14T11:15:20.470919Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/8 duration=13.677ms
ts=2021-03-14T11:15:20.472691Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/98/000000001-000000001.tsm id=0 duration=0.185ms
ts=2021-03-14T11:15:20.473080Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/80 duration=14.104ms
ts=2021-03-14T11:15:20.474655Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/84/000000001-000000001.tsm id=0 duration=0.205ms
ts=2021-03-14T11:15:20.477274Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/81 duration=14.184ms
ts=2021-03-14T11:15:20.479251Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/97 duration=13.820ms
ts=2021-03-14T11:15:20.479105Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/83/000000001-000000001.tsm id=0 duration=0.193ms
ts=2021-03-14T11:15:20.481293Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/85/000000001-000000001.tsm id=0 duration=0.184ms
ts=2021-03-14T11:15:20.486014Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/98 duration=14.805ms
ts=2021-03-14T11:15:20.485984Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/84 duration=12.789ms
ts=2021-03-14T11:15:20.487601Z lvl=info msg="Opened file" log_id=0St6~xNW000 engine=tsm1 service=filestore path=/var/lib/influxdb/data/power/autogen/86/000000001-000000001.tsm id=0 duration=0.201ms
ts=2021-03-14T11:15:20.492158Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/83 duration=14.797ms
ts=2021-03-14T11:15:20.493872Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/85 duration=14.527ms
ts=2021-03-14T11:15:20.497679Z lvl=info msg="Opened shard" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open index_version=inmem path=/var/lib/influxdb/data/power/autogen/86 duration=11.521ms
ts=2021-03-14T11:15:20.508316Z lvl=info msg="Open store (end)" log_id=0St6~xNW000 service=store trace_id=0St6~xmG000 op_name=tsdb_open op_event=end op_elapsed=630.715ms
ts=2021-03-14T11:15:20.508410Z lvl=info msg="Opened service" log_id=0St6~xNW000 service=subscriber
ts=2021-03-14T11:15:20.508441Z lvl=info msg="Starting monitor service" log_id=0St6~xNW000 service=monitor
ts=2021-03-14T11:15:20.508468Z lvl=info msg="Registered diagnostics client" log_id=0St6~xNW000 service=monitor name=build
ts=2021-03-14T11:15:20.508524Z lvl=info msg="Registered diagnostics client" log_id=0St6~xNW000 service=monitor name=runtime
ts=2021-03-14T11:15:20.508552Z lvl=info msg="Registered diagnostics client" log_id=0St6~xNW000 service=monitor name=network
ts=2021-03-14T11:15:20.508605Z lvl=info msg="Registered diagnostics client" log_id=0St6~xNW000 service=monitor name=system
ts=2021-03-14T11:15:20.508644Z lvl=info msg="Starting precreation service" log_id=0St6~xNW000 service=shard-precreation check_interval=10m advance_period=30m
ts=2021-03-14T11:15:20.508695Z lvl=info msg="Starting snapshot service" log_id=0St6~xNW000 service=snapshot
ts=2021-03-14T11:15:20.508720Z lvl=info msg="Starting continuous query service" log_id=0St6~xNW000 service=continuous_querier
ts=2021-03-14T11:15:20.508787Z lvl=info msg="Starting HTTP service" log_id=0St6~xNW000 service=httpd authentication=false
ts=2021-03-14T11:15:20.508813Z lvl=info msg="opened HTTP access log" log_id=0St6~xNW000 service=httpd path=stderr
ts=2021-03-14T11:15:20.509161Z lvl=info msg="Listening on HTTP" log_id=0St6~xNW000 service=httpd addr=[::]:8086 https=false
ts=2021-03-14T11:15:20.509237Z lvl=info msg="Starting retention policy enforcement service" log_id=0St6~xNW000 service=retention check_interval=30m
ts=2021-03-14T11:15:20.509566Z lvl=info msg="Listening for signals" log_id=0St6~xNW000
ts=2021-03-14T11:15:20.702683Z lvl=info msg="Sending usage statistics to usage.influxdata.com" log_id=0St6~xNW000
[httpd] 127.0.0.1 - - [14/Mar/2021:11:15:32 +0000] "GET /ping HTTP/1.1" 204 0 "-" "InfluxDBShell/1.8.4" 97a144fe-84b6-11eb-8001-0242ac130002 223
ts=2021-03-14T11:15:32.779426Z lvl=info msg="Executing query" log_id=0St6~xNW000 service=query query="SELECT count(voltage) FROM power.autogen.hiking WHERE time > 1615585930509531238"
[httpd] 127.0.0.1 - - [14/Mar/2021:11:15:32 +0000] "POST /query?chunked=true&db=power&q=select+count%28voltage%29+from+hiking+where+time+%3E+1615585930509531238 HTTP/1.1" 200 140 "-" "InfluxDBShell/1.8.4" 97a17803-84b6-11eb-8002-0242ac130002 9955
[httpd] 127.0.0.1 - - [14/Mar/2021:11:15:32 +0000] "GET /ping HTTP/1.1" 204 0 "-" "InfluxDBShell/1.8.4" 97a9af89-84b6-11eb-8003-0242ac130002 52
ts=2021-03-14T11:15:32.834488Z lvl=info msg="Executing query" log_id=0St6~xNW000 service=query query="SELECT mean(voltage) FROM power.autogen.hiking WHERE time > 1615585930509531238"
[httpd] 127.0.0.1 - - [14/Mar/2021:11:15:32 +0000] "POST /query?chunked=true&db=power&q=select+mean%28voltage%29+from+hiking+where+time+%3E+1615585930509531238 HTTP/1.1" 200 146 "-" "InfluxDBShell/1.8.4" 97a9e39e-84b6-11eb-8004-0242ac130002 3376
[httpd] 127.0.0.1 - - [14/Mar/2021:11:15:32 +0000] "GET /ping HTTP/1.1" 204 0 "-" "InfluxDBShell/1.8.4" 97b0603c-84b6-11eb-8005-0242ac130002 50
ts=2021-03-14T11:15:32.878031Z lvl=info msg="Executing query" log_id=0St6~xNW000 service=query query="SELECT sum(voltagesquared) FROM (SELECT pow(voltage, 2) AS voltagesquared FROM power.autogen.hiking WHERE time > 1615585930509531238)"
[httpd] 127.0.0.1 - - [14/Mar/2021:11:15:32 +0000] "POST /query?chunked=true&db=power&q=select+sum%28voltagesquared%29+from+%28select+pow%28voltage%2C2%29+as+voltagesquared+from+hiking+where+time+%3E+1615585930509531238%29 HTTP/1.1" 200 139 "-" "InfluxDBShell/1.8.4" 97b0876f-84b6-11eb-8006-0242ac130002 21004
[httpd] 127.0.0.1 - - [14/Mar/2021:11:15:32 +0000] "GET /ping HTTP/1.1" 204 0 "-" "InfluxDBShell/1.8.4" 97b98510-84b6-11eb-8007-0242ac130002 56
ts=2021-03-14T11:15:32.938130Z lvl=info msg="Executing query" log_id=0St6~xNW000 service=query query="SELECT sqrt((sum(voltagesquared) - pow(mean(voltage), 2) * count(voltage)) / (count(voltage) - 1)) FROM power.autogen.hiking, (SELECT pow(voltage, 2) AS voltagesquared FROM power.autogen.hiking) WHERE time > 1615585930509531238"
[httpd] 127.0.0.1 - - [14/Mar/2021:11:15:32 +0000] "POST /query?chunked=true&db=power&q=select+sqrt%28%28sum%28voltagesquared%29+-+pow%28mean%28voltage%29%2C2%29+%2A+count%28voltage%29%29+%2F+%28count%28voltage%29+-+1%29%29+from+hiking%2C+%28select+pow%28voltage%2C2%29+as+voltagesquared+from+hiking%29+where+time+%3E+1615585930509531238 HTTP/1.1" 200 150 "-" "InfluxDBShell/1.8.4" 97b9ad5a-84b6-11eb-8008-0242ac130002 11361
[httpd] 127.0.0.1 - - [14/Mar/2021:11:15:32 +0000] "GET /ping HTTP/1.1" 204 0 "-" "InfluxDBShell/1.8.4" 97c13f21-84b6-11eb-8009-0242ac130002 50
ts=2021-03-14T11:15:32.988376Z lvl=info msg="Executing query" log_id=0St6~xNW000 service=query query="SELECT stddev(voltage) FROM power.autogen.hiking WHERE time > 1615585930509531238"
[httpd] 127.0.0.1 - - [14/Mar/2021:11:15:32 +0000] "POST /query?chunked=true&db=power&q=select+stddev%28voltage%29+from+hiking+where+time+%3E+1615585930509531238 HTTP/1.1" 200 153 "-" "InfluxDBShell/1.8.4" 97c16027-84b6-11eb-800a-0242ac130002 8607
[httpd] 127.0.0.1 - - [14/Mar/2021:11:15:33 +0000] "GET /ping HTTP/1.1" 204 0 "-" "InfluxDBShell/1.8.4" 97c87805-84b6-11eb-800b-0242ac130002 51
ts=2021-03-14T11:15:33.035739Z lvl=info msg="Executing query" log_id=0St6~xNW000 service=query query="SELECT count(voltage) FROM power.autogen.hiking WHERE time >= 1584018002822555618"
[httpd] 127.0.0.1 - - [14/Mar/2021:11:15:33 +0000] "POST /query?chunked=true&db=power&q=select+count%28voltage%29+from+hiking+where+time+%3E%3D+1584018002822555618 HTTP/1.1" 200 142 "-" "InfluxDBShell/1.8.4" 97c8993e-84b6-11eb-800c-0242ac130002 1377562
[httpd] 127.0.0.1 - - [14/Mar/2021:11:15:34 +0000] "GET /ping HTTP/1.1" 204 0 "-" "InfluxDBShell/1.8.4" 98a0b598-84b6-11eb-800d-0242ac130002 50
ts=2021-03-14T11:15:34.453292Z lvl=info msg="Executing query" log_id=0St6~xNW000 service=query query="SELECT mean(voltage) FROM power.autogen.hiking WHERE time >= 1584018002822555618"
[httpd] 127.0.0.1 - - [14/Mar/2021:11:15:34 +0000] "POST /query?chunked=true&db=power&q=select+mean%28voltage%29+from+hiking+where+time+%3E%3D+1584018002822555618 HTTP/1.1" 200 151 "-" "InfluxDBShell/1.8.4" 98a0e70a-84b6-11eb-800e-0242ac130002 1798176
[httpd] 127.0.0.1 - - [14/Mar/2021:11:15:36 +0000] "GET /ping HTTP/1.1" 204 0 "-" "InfluxDBShell/1.8.4" 99b92df5-84b6-11eb-800f-0242ac130002 57
ts=2021-03-14T11:15:36.291593Z lvl=info msg="Executing query" log_id=0St6~xNW000 service=query query="SELECT sum(voltagesquared) FROM (SELECT pow(voltage, 2) AS voltagesquared FROM power.autogen.hiking WHERE time >= 1584018002822555618)"
[httpd] 127.0.0.1 - - [14/Mar/2021:11:15:36 +0000] "POST /query?chunked=true&db=power&q=select+sum%28voltagesquared%29+from+%28select+pow%28voltage%2C2%29+as+voltagesquared+from+hiking+where+time+%3E%3D+1584018002822555618%29 HTTP/1.1" 200 141 "-" "InfluxDBShell/1.8.4" 99b9660a-84b6-11eb-8010-0242ac130002 26132230
[httpd] 127.0.0.1 - - [14/Mar/2021:11:16:02 +0000] "GET /ping HTTP/1.1" 204 0 "-" "InfluxDBShell/1.8.4" a952d5f6-84b6-11eb-8011-0242ac130002 51
ts=2021-03-14T11:16:02.464451Z lvl=info msg="Executing query" log_id=0St6~xNW000 service=query query="SELECT sqrt((sum(voltagesquared) - pow(mean(voltage), 2) * count(voltage)) / (count(voltage) - 1)) FROM power.autogen.hiking, (SELECT pow(voltage, 2) AS voltagesquared FROM power.autogen.hiking) WHERE time >= 1584018002822555618"
[httpd] 127.0.0.1 - - [14/Mar/2021:11:16:02 +0000] "POST /query?chunked=true&db=power&q=select+sqrt%28%28sum%28voltagesquared%29+-+pow%28mean%28voltage%29%2C2%29+%2A+count%28voltage%29%29+%2F+%28count%28voltage%29+-+1%29%29+from+hiking%2C+%28select+pow%28voltage%2C2%29+as+voltagesquared+from+hiking%29+where+time+%3E%3D+1584018002822555618 HTTP/1.1" 200 150 "-" "InfluxDBShell/1.8.4" a953090d-84b6-11eb-8012-0242ac130002 29312057
[httpd] 127.0.0.1 - - [14/Mar/2021:11:16:31 +0000] "GET /ping HTTP/1.1" 204 0 "-" "InfluxDBShell/1.8.4" bad186c7-84b6-11eb-8013-0242ac130002 51
ts=2021-03-14T11:16:31.815681Z lvl=info msg="Executing query" log_id=0St6~xNW000 service=query query="SELECT stddev(voltage) FROM power.autogen.hiking WHERE time >= 1584018002822555618"
[httpd] 127.0.0.1 - - [14/Mar/2021:11:16:31 +0000] "POST /query?chunked=true&db=power&q=select+stddev%28voltage%29+from+hiking+where+time+%3E%3D+1584018002822555618 HTTP/1.1" 200 152 "-" "InfluxDBShell/1.8.4" bad1b2f5-84b6-11eb-8014-0242ac130002 29853596
[httpd] 127.0.0.1 - - [14/Mar/2021:11:17:01 +0000] "GET /ping HTTP/1.1" 204 0 "-" "InfluxDBShell/1.8.4" cca2e875-84b6-11eb-8015-0242ac130002 51
ts=2021-03-14T11:17:01.709194Z lvl=info msg="Executing query" log_id=0St6~xNW000 service=query query="SELECT count(voltage) FROM power.autogen.hiking"
[httpd] 127.0.0.1 - - [14/Mar/2021:11:17:01 +0000] "POST /query?chunked=true&db=power&q=select+count%28voltage%29+from+hiking HTTP/1.1" 200 132 "-" "InfluxDBShell/1.8.4" cca31ac3-84b6-11eb-8016-0242ac130002 3731719
[httpd] 127.0.0.1 - - [14/Mar/2021:11:17:05 +0000] "GET /ping HTTP/1.1" 204 0 "-" "InfluxDBShell/1.8.4" cee22e9f-84b6-11eb-8017-0242ac130002 52
ts=2021-03-14T11:17:05.479224Z lvl=info msg="Executing query" log_id=0St6~xNW000 service=query query="SELECT mean(voltage) FROM power.autogen.hiking"
[httpd] 127.0.0.1 - - [14/Mar/2021:11:17:05 +0000] "POST /query?chunked=true&db=power&q=select+mean%28voltage%29+from+hiking HTTP/1.1" 200 141 "-" "InfluxDBShell/1.8.4" cee256b7-84b6-11eb-8018-0242ac130002 4871945
[httpd] 127.0.0.1 - - [14/Mar/2021:11:17:10 +0000] "GET /ping HTTP/1.1" 204 0 "-" "InfluxDBShell/1.8.4" d1cf6930-84b6-11eb-8019-0242ac130002 50
ts=2021-03-14T11:17:10.389619Z lvl=info msg="Executing query" log_id=0St6~xNW000 service=query query="SELECT sum(voltagesquared) FROM (SELECT pow(voltage, 2) AS voltagesquared FROM power.autogen.hiking)"
[httpd] 127.0.0.1 - - [14/Mar/2021:11:17:10 +0000] "POST /query?chunked=true&db=power&q=select+sum%28voltagesquared%29+from+%28select+pow%28voltage%2C2%29+as+voltagesquared+from+hiking%29 HTTP/1.1" 200 142 "-" "InfluxDBShell/1.8.4" d1cf9a74-84b6-11eb-801a-0242ac130002 76209315
[httpd] 127.0.0.1 - - [14/Mar/2021:11:18:26 +0000] "GET /ping HTTP/1.1" 204 0 "-" "InfluxDBShell/1.8.4" ff422a98-84b6-11eb-801b-0242ac130002 54
ts=2021-03-14T11:18:26.639327Z lvl=info msg="Executing query" log_id=0St6~xNW000 service=query query="SELECT sqrt((sum(voltagesquared) - pow(mean(voltage), 2) * count(voltage)) / (count(voltage) - 1)) FROM power.autogen.hiking, (SELECT pow(voltage, 2) AS voltagesquared FROM power.autogen.hiking)"
[httpd] 127.0.0.1 - - [14/Mar/2021:11:18:26 +0000] "POST /query?chunked=true&db=power&q=select+sqrt%28%28sum%28voltagesquared%29+-+pow%28mean%28voltage%29%2C2%29+%2A+count%28voltage%29%29+%2F+%28count%28voltage%29+-+1%29%29+from+hiking%2C+%28select+pow%28voltage%2C2%29+as+voltagesquared+from+hiking%29 HTTP/1.1" 200 143 "-" "InfluxDBShell/1.8.4" ff4260cf-84b6-11eb-801c-0242ac130002 84433320
[httpd] 127.0.0.1 - - [14/Mar/2021:11:19:51 +0000] "GET /ping HTTP/1.1" 204 0 "-" "InfluxDBShell/1.8.4" 319b9266-84b7-11eb-801d-0242ac130002 51
ts=2021-03-14T11:19:51.110813Z lvl=info msg="Executing query" log_id=0St6~xNW000 service=query query="SELECT stddev(voltage) FROM power.autogen.hiking"
runtime: out of memory: cannot allocate 587513856-byte block (2003533824 in use)
fatal error: out of memory

runtime stack:
runtime.throw(0xfd75ae, 0xd)
/usr/local/go/src/runtime/panic.go:774 +0x5c
runtime.largeAlloc(0x2304c000, 0x39070001, 0xa6c4a140)
/usr/local/go/src/runtime/malloc.go:1140 +0x124
runtime.mallocgc.func1()
/usr/local/go/src/runtime/malloc.go:1033 +0x38
runtime.systemstack(0x484ed20)
/usr/local/go/src/runtime/asm_arm.s:354 +0x84
runtime.mstart()
/usr/local/go/src/runtime/proc.go:1146

goroutine 4356 [running]:
runtime.systemstack_switch()
/usr/local/go/src/runtime/asm_arm.s:298 +0x4 fp=0x4e9f3a4 sp=0x4e9f3a0 pc=0x718d8
runtime.mallocgc(0x2304c000, 0xf232f0, 0x1, 0x4ba09c)
/usr/local/go/src/runtime/malloc.go:1032 +0x8d0 fp=0x4e9f40c sp=0x4e9f3a4 pc=0x1bb70
runtime.growslice(0xf232f0, 0x57d74000, 0x801124, 0x801124, 0x801125, 0x57d74000, 0x667400, 0x5917f80)
/usr/local/go/src/runtime/slice.go:181 +0x190 fp=0x4e9f43c sp=0x4e9f40c pc=0x595bc
github.com/influxdata/influxdb/query.(*FloatSliceFuncReducer).AggregateFloat(0x53b9bd0, 0x5917f80)
/go/src/github.com/influxdata/influxdb/query/functions.gen.go:99 +0xd0 fp=0x4e9f4d8 sp=0x4e9f43c pc=0x4a6be8
github.com/influxdata/influxdb/query.(*floatReduceFloatIterator).reduce(0x4ca9d40, 0x0, 0x0, 0x0, 0x0, 0x0)
/go/src/github.com/influxdata/influxdb/query/iterator.gen.go:1114 +0x2f0 fp=0x4e9f7b0 sp=0x4e9f4d8 pc=0x4bd400
github.com/influxdata/influxdb/query.(*floatReduceFloatIterator).Next(0x4ca9d40, 0x80000000, 0xfffffffe, 0x7fffffff)
/go/src/github.com/influxdata/influxdb/query/iterator.gen.go:1026 +0x68 fp=0x4e9f7cc sp=0x4e9f7b0 pc=0x4bd084
github.com/influxdata/influxdb/query.(*floatIntervalIterator).Next(0x4d82d20, 0x4f09c8, 0xe43e70, 0xf1bba8)
/go/src/github.com/influxdata/influxdb/query/iterator.gen.go:897 +0x2c fp=0x4e9f90c sp=0x4e9f7cc pc=0x4bcd54
github.com/influxdata/influxdb/query.(*floatInterruptIterator).Next(0x53b9b60, 0x4f080c, 0xe43e70, 0xf1bba8)
/go/src/github.com/influxdata/influxdb/query/iterator.gen.go:941 +0x44 fp=0x4e9f920 sp=0x4e9f90c pc=0x4bcef8
github.com/influxdata/influxdb/query.(*bufFloatIterator).Next(0x53b9b80, 0x4b77240, 0x1, 0x0)
/go/src/github.com/influxdata/influxdb/query/iterator.gen.go:90 +0x74 fp=0x4e9f934 sp=0x4e9f920 pc=0x4b84a0
github.com/influxdata/influxdb/query.(*bufFloatIterator).peek(0x53b9b80, 0x0, 0x0, 0x1b7a8)
/go/src/github.com/influxdata/influxdb/query/iterator.gen.go:65 +0x1c fp=0x4e9f948 sp=0x4e9f934 pc=0x4b83ac
github.com/influxdata/influxdb/query.(*floatIteratorScanner).Peek(0x51a2720, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x4f326a0)
/go/src/github.com/influxdata/influxdb/query/iterator.gen.go:516 +0x2c fp=0x4e9f95c sp=0x4e9f948 pc=0x4bab78
github.com/influxdata/influxdb/query.(*scannerCursor).scan(0x4ca05a0, 0x51a2760, 0x0, 0x0, 0x0, 0x13, 0x10000, 0x1e6b012, 0xa6d2b997)
/go/src/github.com/influxdata/influxdb/query/cursor.go:243 +0x28 fp=0x4e9f9a0 sp=0x4e9f95c pc=0x4a1730
github.com/influxdata/influxdb/query.(*scannerCursor).scan-fm(0x51a2760, 0x1eb24a0, 0x1b59c, 0x1be04, 0x4da1170, 0x30, 0x30, 0xeb6b38)
/go/src/github.com/influxdata/influxdb/query/cursor.go:242 +0x24 fp=0x4e9f9c8 sp=0x4e9f9a0 pc=0x507784
github.com/influxdata/influxdb/query.(*scannerCursorBase).Scan(0x4ca05a8, 0x4da1170, 0x7fffff00)
/go/src/github.com/influxdata/influxdb/query/cursor.go:184 +0x2c fp=0x4e9fa54 sp=0x4e9f9c8 pc=0x4a0eb0
github.com/influxdata/influxdb/query.(*Emitter).Emit(0x5973ac0, 0x4ca05a0, 0x2710, 0x5973ac0, 0x4ebc01c)
/go/src/github.com/influxdata/influxdb/query/emitter.go:41 +0x40 fp=0x4e9fb58 sp=0x4e9fa54 pc=0x4a2d48
github.com/influxdata/influxdb/coordinator.(*StatementExecutor).executeSelectStatement(0x4ac5e40, 0x4e6b980, 0x4f1ecb0, 0x0, 0x0)
/go/src/github.com/influxdata/influxdb/coordinator/statement_executor.go:561 +0x110 fp=0x4e9fcb0 sp=0x4e9fb58 pc=0x663040
github.com/influxdata/influxdb/coordinator.(*StatementExecutor).ExecuteStatement(0x4ac5e40, 0x1eafd90, 0x4e6b980, 0x4f1ecb0, 0x1, 0x1)
/go/src/github.com/influxdata/influxdb/coordinator/statement_executor.go:64 +0x30c4 fp=0x4e9fdac sp=0x4e9fcb0 pc=0x6613a0
github.com/influxdata/influxdb/query.(*Executor).executeQuery(0x4b4f9e0, 0x4e8e080, 0x4ebc01c, 0x5, 0x0, 0x0, 0x1eb0210, 0x2b5a4f0, 0x2710, 0x0, ...)
/go/src/github.com/influxdata/influxdb/query/executor.go:335 +0xb68 fp=0x4e9ffac sp=0x4e9fdac pc=0x4a4d64
runtime.goexit()
/usr/local/go/src/runtime/asm_arm.s:868 +0x4 fp=0x4e9ffac sp=0x4e9ffac pc=0x73610
created by github.com/influxdata/influxdb/query.(*Executor).ExecuteQuery
/go/src/github.com/influxdata/influxdb/query/executor.go:237 +0x6c

goroutine 1 [chan receive, 5 minutes]:
main.(*Main).Run(0x4a85f8c, 0x480e038, 0x0, 0x0, 0x2b4a4d0, 0x4892030)
/go/src/github.com/influxdata/influxdb/cmd/influxd/main.go:90 +0x228
main.main()
/go/src/github.com/influxdata/influxdb/cmd/influxd/main.go:45 +0x140

goroutine 6 [syscall, 5 minutes]:
os/signal.signal_recv(0x0)
/usr/local/go/src/runtime/sigqueue.go:147 +0x130
os/signal.loop()
/usr/local/go/src/os/signal/signal_unix.go:23 +0x14
created by os/signal.init.0
/usr/local/go/src/os/signal/signal_unix.go:29 +0x30

goroutine 9 [select]:
github.com/influxdata/influxdb/vendor/go.opencensus.io/stats/view.(*worker).start(0x4aa9d00)
/go/src/github.com/influxdata/influxdb/vendor/go.opencensus.io/stats/view/worker.go:154 +0xb0
created by github.com/influxdata/influxdb/vendor/go.opencensus.io/stats/view.init.0
/go/src/github.com/influxdata/influxdb/vendor/go.opencensus.io/stats/view/worker.go:32 +0x48

goroutine 10 [IO wait, 5 minutes]:
internal/poll.runtime_pollWait(0xa6c7a020, 0x72, 0x0)
/usr/local/go/src/runtime/netpoll.go:184 +0x44
internal/poll.(*pollDesc).wait(0x48378c4, 0x72, 0x0, 0x0, 0xfcddce)
/usr/local/go/src/internal/poll/fd_poll_runtime.go:87 +0x30
internal/poll.(*pollDesc).waitRead(...)
/usr/local/go/src/internal/poll/fd_poll_runtime.go:92
internal/poll.(*FD).Accept(0x48378b0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0)
/usr/local/go/src/internal/poll/fd_unix.go:384 +0x1a8
net.(*netFD).accept(0x48378b0, 0x0, 0xa7801, 0x0)
/usr/local/go/src/net/fd_unix.go:238 +0x20
net.(*TCPListener).accept(0x4b072f0, 0x4ac5ec0, 0x40000000, 0x0)
/usr/local/go/src/net/tcpsock_posix.go:139 +0x20
net.(*TCPListener).Accept(0x4b072f0, 0x0, 0x0, 0x0, 0x0)
/usr/local/go/src/net/tcpsock.go:261 +0x3c
github.com/influxdata/influxdb/tcp.(*Mux).Serve(0x4ac5ec0, 0x1eacdb0, 0x4b072f0, 0x4b072f0, 0x0)
/go/src/github.com/influxdata/influxdb/tcp/mux.go:75 +0x60
created by github.com/influxdata/influxdb/cmd/influxd/run.(*Server).Open
/go/src/github.com/influxdata/influxdb/cmd/influxd/run/server.go:393 +0x1f0

goroutine 1886 [select]:
github.com/influxdata/influxdb/tsdb.(*Store).monitorShards(0x48e0640)
/go/src/github.com/influxdata/influxdb/tsdb/store.go:1935 +0x118
github.com/influxdata/influxdb/tsdb.(*Store).Open.func1(0x48e0640)
/go/src/github.com/influxdata/influxdb/tsdb/store.go:232 +0x38
created by github.com/influxdata/influxdb/tsdb.(*Store).Open
/go/src/github.com/influxdata/influxdb/tsdb/store.go:230 +0x218

goroutine 1895 [select, 5 minutes]:
github.com/influxdata/influxdb/cmd/influxd/run.(*Command).monitorServerErrors(0x4aee540)
/go/src/github.com/influxdata/influxdb/cmd/influxd/run/command.go:174 +0x134
created by github.com/influxdata/influxdb/cmd/influxd/run.(*Command).Run
/go/src/github.com/influxdata/influxdb/cmd/influxd/run/command.go:155 +0x8d8

goroutine 4394 [chan receive]:
github.com/influxdata/influxdb/services/httpd.(*Handler).serveQuery(0x48cf100, 0xa6c35360, 0x4e8e030, 0x4e6b900, 0x0, 0x0)
/go/src/github.com/influxdata/influxdb/services/httpd/handler.go:655 +0x644
github.com/influxdata/influxdb/services/httpd.authenticate.func1(0xa6c35360, 0x4e8e030, 0x4e6b900)
/go/src/github.com/influxdata/influxdb/services/httpd/handler.go:1753 +0x6fc
net/http.HandlerFunc.ServeHTTP(0x4b07540, 0xa6c35360, 0x4e8e030, 0x4e6b900)
/usr/local/go/src/net/http/server.go:2007 +0x34
github.com/influxdata/influxdb/services/httpd.(*Handler).responseWriter.func1(0x1ea8e90, 0x12854180, 0x4e6b900)
/go/src/github.com/influxdata/influxdb/services/httpd/handler.go:1956 +0x84
net/http.HandlerFunc.ServeHTTP(0x4b07550, 0x1ea8e90, 0x12854180, 0x4e6b900)
/usr/local/go/src/net/http/server.go:2007 +0x34
github.com/influxdata/influxdb/services/httpd.gzipFilter.func1(0x1ea8ed0, 0x4e8e020, 0x4e6b900)
/go/src/github.com/influxdata/influxdb/services/httpd/gzip.go:39 +0x1c8
net/http.HandlerFunc.ServeHTTP(0x4b07560, 0x1ea8ed0, 0x4e8e020, 0x4e6b900)
/usr/local/go/src/net/http/server.go:2007 +0x34
net/http.HandlerFunc.ServeHTTP(0x4b07570, 0x1ea8ed0, 0x4e8e020, 0x4e6b900)
/usr/local/go/src/net/http/server.go:2007 +0x34
github.com/influxdata/influxdb/services/httpd.cors.func1(0x1ea8ed0, 0x4e8e020, 0x4e6b900)
/go/src/github.com/influxdata/influxdb/services/httpd/handler.go:1880 +0x90
net/http.HandlerFunc.ServeHTTP(0x4b07580, 0x1ea8ed0, 0x4e8e020, 0x4e6b900)
/usr/local/go/src/net/http/server.go:2007 +0x34
github.com/influxdata/influxdb/services/httpd.requestID.func1(0x1ea8ed0, 0x4e8e020, 0x4e6b900)
/go/src/github.com/influxdata/influxdb/services/httpd/handler.go:1911 +0x2c8
net/http.HandlerFunc.ServeHTTP(0x4b07590, 0x1ea8ed0, 0x4e8e020, 0x4e6b900)
/usr/local/go/src/net/http/server.go:2007 +0x34
github.com/influxdata/influxdb/services/httpd.(*Handler).logging.func1(0x1ea8ed0, 0x4e8e010, 0x4e6b900)
/go/src/github.com/influxdata/influxdb/services/httpd/handler.go:1937 +0x94
net/http.HandlerFunc.ServeHTTP(0x4b075a0, 0x1ea8ed0, 0x4e8e010, 0x4e6b900)
/usr/local/go/src/net/http/server.go:2007 +0x34
github.com/influxdata/influxdb/services/httpd.(*Handler).recovery.func1(0x1eacf10, 0x4bf2090, 0x4e6b900)
/go/src/github.com/influxdata/influxdb/services/httpd/handler.go:1993 +0xec
net/http.HandlerFunc.ServeHTTP(0x4b075b0, 0x1eacf10, 0x4bf2090, 0x4e6b900)
/usr/local/go/src/net/http/server.go:2007 +0x34
github.com/influxdata/influxdb/vendor/github.com/bmizerany/pat.(*PatternServeMux).ServeHTTP(0x4b07310, 0x1eacf10, 0x4bf2090, 0x4e6b900)
/go/src/github.com/influxdata/influxdb/vendor/github.com/bmizerany/pat/mux.go:117 +0xfc
github.com/influxdata/influxdb/services/httpd.(*Handler).ServeHTTP(0x48cf100, 0x1eacf10, 0x4bf2090, 0x4e6b900)
/go/src/github.com/influxdata/influxdb/services/httpd/handler.go:459 +0x320
net/http.serverHandler.ServeHTTP(0x4bf3b00, 0x1eacf10, 0x4bf2090, 0x4e6b900)
/usr/local/go/src/net/http/server.go:2802 +0x88
net/http.(*conn).serve(0x4aae6c0, 0x1eaf350, 0x4f32260)
/usr/local/go/src/net/http/server.go:1890 +0x7e0
created by net/http.(*Server).Serve
/usr/local/go/src/net/http/server.go:2928 +0x2e4

goroutine 4340 [select]:
github.com/influxdata/influxdb/query.(*ExecutionContext).watch.func1(0x4f1ecb0)
/go/src/github.com/influxdata/influxdb/query/execution_context.go:47 +0xe0
created by github.com/influxdata/influxdb/query.(*ExecutionContext).watch
/go/src/github.com/influxdata/influxdb/query/execution_context.go:39 +0x74

goroutine 1889 [select, 5 minutes]:
github.com/influxdata/influxdb/services/precreator.(*Service).runPrecreation(0x4b54690)
/go/src/github.com/influxdata/influxdb/services/precreator/service.go:76 +0xc8
created by github.com/influxdata/influxdb/services/precreator.(*Service).Open
/go/src/github.com/influxdata/influxdb/services/precreator/service.go:54 +0x164

goroutine 1887 [select, 5 minutes]:
github.com/influxdata/influxdb/services/subscriber.(*Service).run(0x48cf080)
/go/src/github.com/influxdata/influxdb/services/subscriber/service.go:239 +0x1f0
github.com/influxdata/influxdb/services/subscriber.(*Service).Open.func1(0x48cf080)
/go/src/github.com/influxdata/influxdb/services/subscriber/service.go:98 +0x50
created by github.com/influxdata/influxdb/services/subscriber.(*Service).Open
/go/src/github.com/influxdata/influxdb/services/subscriber/service.go:96 +0x144

goroutine 1891 [select]:
github.com/influxdata/influxdb/services/continuous_querier.(*Service).backgroundLoop(0x4aeee40)
/go/src/github.com/influxdata/influxdb/services/continuous_querier/service.go:215 +0x114
created by github.com/influxdata/influxdb/services/continuous_querier.(*Service).Open
/go/src/github.com/influxdata/influxdb/services/continuous_querier/service.go:133 +0xf0

goroutine 1892 [IO wait]:
internal/poll.runtime_pollWait(0xa6c79f9c, 0x72, 0x0)
/usr/local/go/src/runtime/netpoll.go:184 +0x44
internal/poll.(*pollDesc).wait(0x4e4c244, 0x72, 0x0, 0x0, 0xfcddce)
/usr/local/go/src/internal/poll/fd_poll_runtime.go:87 +0x30
internal/poll.(*pollDesc).waitRead(...)
/usr/local/go/src/internal/poll/fd_poll_runtime.go:92
internal/poll.(*FD).Accept(0x4e4c230, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0)
/usr/local/go/src/internal/poll/fd_unix.go:384 +0x1a8
net.(*netFD).accept(0x4e4c230, 0x2b5c138, 0x0, 0x1)
/usr/local/go/src/net/fd_unix.go:238 +0x20
net.(*TCPListener).accept(0x480daa0, 0x4aae700, 0x4df15700, 0x4b074)
/usr/local/go/src/net/tcpsock_posix.go:139 +0x20
net.(*TCPListener).Accept(0x480daa0, 0x4d1ced8, 0xc, 0x4ce70a0, 0x347188)
/usr/local/go/src/net/tcpsock.go:261 +0x3c
net/http.(*Server).Serve(0x4bf3b00, 0x1eacdb0, 0x480daa0, 0x0, 0x0)
/usr/local/go/src/net/http/server.go:2896 +0x214
net/http.Serve(...)
/usr/local/go/src/net/http/server.go:2468
github.com/influxdata/influxdb/services/httpd.(*Service).serve(0x4aeeea0, 0x1eacdb0, 0x480daa0)
/go/src/github.com/influxdata/influxdb/services/httpd/service.go:250 +0x64
github.com/influxdata/influxdb/services/httpd.(*Service).serveTCP(0x4aeeea0)
/go/src/github.com/influxdata/influxdb/services/httpd/service.go:238 +0x2c
created by github.com/influxdata/influxdb/services/httpd.(*Service).Open
/go/src/github.com/influxdata/influxdb/services/httpd/service.go:187 +0x45c

goroutine 1890 [select, 5 minutes]:
github.com/influxdata/influxdb/tcp.(*listener).Accept(0x4b549f0, 0x0, 0x0, 0x0, 0x0)
/go/src/github.com/influxdata/influxdb/tcp/mux.go:236 +0xe0
github.com/influxdata/influxdb/services/snapshotter.(*Service).serve(0x4b546f0)
/go/src/github.com/influxdata/influxdb/services/snapshotter/service.go:94 +0x5c
created by github.com/influxdata/influxdb/services/snapshotter.(*Service).Open
/go/src/github.com/influxdata/influxdb/services/snapshotter/service.go:68 +0x74

goroutine 1888 [select, 5 minutes]:
github.com/influxdata/influxdb/services/subscriber.(*Service).waitForMetaUpdates(0x48cf080)
/go/src/github.com/influxdata/influxdb/services/subscriber/service.go:165 +0x8c
github.com/influxdata/influxdb/services/subscriber.(*Service).Open.func2(0x48cf080)
/go/src/github.com/influxdata/influxdb/services/subscriber/service.go:102 +0x50
created by github.com/influxdata/influxdb/services/subscriber.(*Service).Open
/go/src/github.com/influxdata/influxdb/services/subscriber/service.go:100 +0x160

goroutine 1893 [select, 5 minutes]:
github.com/influxdata/influxdb/services/retention.(*Service).run(0x4b549c0)
/go/src/github.com/influxdata/influxdb/services/retention/service.go:78 +0x77c
github.com/influxdata/influxdb/services/retention.(*Service).Open.func1(0x4b549c0)
/go/src/github.com/influxdata/influxdb/services/retention/service.go:51 +0x50
created by github.com/influxdata/influxdb/services/retention.(*Service).Open
/go/src/github.com/influxdata/influxdb/services/retention/service.go:51 +0x130

goroutine 1894 [select, 5 minutes]:
github.com/influxdata/influxdb/cmd/influxd/run.(*Server).startServerReporting(0x48dc6e0)
/go/src/github.com/influxdata/influxdb/cmd/influxd/run/server.go:521 +0xd0
created by github.com/influxdata/influxdb/cmd/influxd/run.(*Server).Open
/go/src/github.com/influxdata/influxdb/cmd/influxd/run/server.go:466 +0xb8c

goroutine 4339 [select]:
github.com/influxdata/influxdb/query.(*TaskManager).waitForQuery(0x4ac5e00, 0xf, 0x0, 0x4f58140, 0x4d06340, 0x4f581c0)
/go/src/github.com/influxdata/influxdb/query/task_manager.go:289 +0xd0
created by github.com/influxdata/influxdb/query.(*TaskManager).AttachQuery
/go/src/github.com/influxdata/influxdb/query/task_manager.go:196 +0x308

goroutine 4354 [IO wait]:
internal/poll.runtime_pollWait(0xa6c79e94, 0x72, 0xffffffff)
/usr/local/go/src/runtime/netpoll.go:184 +0x44
internal/poll.(*pollDesc).wait(0x4e4c104, 0x72, 0x0, 0x1, 0xffffffff)
/usr/local/go/src/internal/poll/fd_poll_runtime.go:87 +0x30
internal/poll.(*pollDesc).waitRead(...)
/usr/local/go/src/internal/poll/fd_poll_runtime.go:92
internal/poll.(*FD).Read(0x4e4c0f0, 0x4f3228d, 0x1, 0x1, 0x0, 0x0, 0x0)
/usr/local/go/src/internal/poll/fd_unix.go:169 +0x178
net.(*netFD).Read(0x4e4c0f0, 0x4f3228d, 0x1, 0x1, 0x5024f40, 0x0, 0x4f322f0)
/usr/local/go/src/net/fd_unix.go:202 +0x38
net.(*conn).Read(0x4d98000, 0x4f3228d, 0x1, 0x1, 0x0, 0x0, 0x0)
/usr/local/go/src/net/net.go:184 +0x58
net/http.(*connReader).backgroundRead(0x4f32280)
/usr/local/go/src/net/http/server.go:677 +0x44
created by net/http.(*connReader).startBackgroundRead
/usr/local/go/src/net/http/server.go:673 +0xb8

goroutine 4355 [select]:
github.com/influxdata/influxdb/services/httpd.(*Handler).serveQuery.func2(0x4d06540, 0x4d062c0, 0x4d06340)
/go/src/github.com/influxdata/influxdb/services/httpd/handler.go:619 +0x68
created by github.com/influxdata/influxdb/services/httpd.(*Handler).serveQuery
/go/src/github.com/influxdata/influxdb/services/httpd/handler.go:616 +0xf44
Stopping influxdb ... done
Going to remove influxdb
Removing influxdb ... done

SQLite Test Group 1 (1000 records)
count(voltage)
--------------
1000
Wallclock: 0:01.92

avg(voltage)
------------
249.9352
Wallclock: 0:01.93

sum(pow(voltage,2))
-------------------
62475887.76
Wallclock: 0:01.95

stddev
----------------
2.87955774605404
Wallclock: 0:01.93

SQLite Test Group 2 (approximately a year of records)
count(voltage)
--------------
3163157
Wallclock: 0:02.14

avg(voltage)
----------------
246.492460601855
Wallclock: 0:02.15

sum(pow(voltage,2))
-------------------
192211041158.786
Wallclock: 0:02.50

stddev
----------------
2.65289167755027
Wallclock: 0:02.90

SQLite Test Group (all records)
count(voltage)
--------------
9150127
Wallclock: 0:01.95

avg(voltage)
----------------
246.261730356299
Wallclock: 0:02.03

sum(pow(voltage,2))
-------------------
554970818683.556
Wallclock: 0:02.82

stddev
----------------
2.62046183841889
Wallclock: 0:03.80

$
```

Contributor guide

Open the contributing guide

Research direction

Start by reviewing the mentioned run_standard_deviation_tests and influx_tests scripts, then reproduce the stddev(voltage) query against the supplied large-data test setup. Trace the stddev entry point and compare its behavior with the inline calculation. Done means the large query returns the expected standard deviation without an out-of-memory crash.

Written by the indexing model from the issue text.

Assessment

Tech stack: docker, shell, sqlite
Domain: databases, performance
Issue type: Bug
Difficulty: 4/5
Estimated time: 3-5 days
Activity status: Stale
Clarity: Mostly clear
Newbie friendliness: 28/100

stddev() function crashes on large data sets

Description

Contributor guide

Research direction

Assessment

Get new issues in your inbox