Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Slow writes / memory peaks / crashes caused by large amounts of shards creation #6635

Closed
francisdb opened this issue May 16, 2016 · 23 comments
Closed

Comments

@francisdb
Copy link

francisdb commented May 16, 2016

We are having a lot of problems with our influx instance and while debugging we saw this:

(disk io charts are totals)
influx-issues

machine:
AWS m3.xlarge (seen same on c3.xlarge)

Influx 0.12.2 (on 0.13.0 we even have more problems)
Seen issues on both Ubuntu 14.04 and 16.04
default .deb file install with auth enabled
/data mounted on local ssd1
/wal mounted on local ssd2

We have only ~100 measures
Each with one field and one tag (max 800 values)
Writes about 3 per second (1-8 or ~1000 rows)
Queries about 3 per minute (small bursts)

Steps to reproduce:

Just keep a system running and wait for the retention policy to kick in

Expected behavior:

Fast removal of old data, no acces to disk outside of /data

Actual behavior:

Takes long time to apply the retention policy, a lot of access outside of /data and /wal

Additional info:

During this period we see write speeds go up from ~150 ms to 40s and sometimes the server just goes out of memory

@jwilder
Copy link
Contributor

jwilder commented May 16, 2016

a lot of access outside of /data and /wal

Can you describe what you are seeing? What writes or data are outside of those dirs?

@francisdb
Copy link
Author

I have no idea what is written (tmp files?), this machine only has ubuntu, influx and telegraf (stores to other influx host), no swap file

EBS mounted on / (slow)
SSD1 mounted on /var/lib/influxdb/data/
SSD2 mounted on /var/lib/influxdb/wal/

As you can see during retention policy actions we have a lot of writes on our slow disk.

In the big picture we are trying to find out why these writes are so slow (40s)
Why our system goes out of memory all the time (buffers filling up in memory?)

I guess this all is not normal for a small database:
84M data
3.6M meta
34M wal

@jwilder
Copy link
Contributor

jwilder commented May 16, 2016

@francisdb Are you swapping? You could try running iotop and see if you can identify a process writing during those times.

Also, when you're writes are slow, can you grab some profiles with:

curl -o heap.txt "http://localhost:8086/debug/pprof/heap?debug=1"
curl -o goroutine.txt "http://localhost:8086/debug/pprof/goroutine?debug=1"

@francisdb
Copy link
Author

we have no swap file, let me have a go at getting those profiles

@francisdb
Copy link
Author

Is there a way to get more internal logging?
As for iotop there is io load mainly by influxd and also some jbd2
We set up a script to start dumps when used mem > 95%, results attached

20160516-16:30:46:910736791-goroutine.txt
20160516-16:30:46:910736791-heap.txt
20160516-16:30:52:325181203-goroutine.txt
20160516-16:30:52:325181203-heap.txt
(had to force a restart here because influx was stuck on 100% ram, again started with quite high mem use after, 12G)
20160516-16:34:53:690865027-goroutine.txt
20160516-16:34:53:690865027-heap.txt

@pizuricv
Copy link

Screenshot from AWS of this instance, you can see a high correlation between disk reads (orange) and CPU utilization, that goes up to 100% on 4CPU machine!
image

@pizuricv
Copy link

pizuricv commented May 16, 2016

Here is the log where you can see inserts happening in more than 2 minutes:

[tsm1] 2016/05/16 18:33:50 /var/lib/influxdb/data/server/default/83512 database index loaded in 1.061µs
[http] 2016/05/16 18:33:50 172.31.17.25 - dashboard [16/May/2016:18:31:40 +0000] POST /write?db=server&precision=ms HTTP/1.1 204 0 - AHC/1.0 6eb824f9-1b94-11e6-9855-000000000000 2m10.085028966s
[tsm1wal] 2016/05/16 18:33:50 tsm1 WAL starting with 10485760 segment size
[tsm1wal] 2016/05/16 18:33:50 tsm1 WAL writing to /var/lib/influxdb/wal/server/default/83511
[tsm1] 2016/05/16 18:33:50 /var/lib/influxdb/data/server/default/83511 database index loaded in 1.223µs
[http] 2016/05/16 18:33:50 172.31.17.25 - dashboard [16/May/2016:18:31:44 +0000] POST /write?db=server&precision=ms HTTP/1.1 204 0 - AHC/1.0 70ff9be8-1b94-11e6-98be-000000000000 2m6.273279673s
[tsm1wal] 2016/05/16 18:33:50 tsm1 WAL starting with 10485760 segment size
[tsm1wal] 2016/05/16 18:33:50 tsm1 WAL writing to /var/lib/influxdb/wal/server/default/83505
[tsm1] 2016/05/16 18:33:50 /var/lib/influxdb/data/server/default/83505 database index loaded in 1.251µs
[http] 2016/05/16 18:33:50 172.31.17.25 - dashboard [16/May/2016:18:31:47 +0000] POST /write?db=server&precision=ms HTTP/1.1 204 0 - AHC/1.0 72cb755b-1b94-11e6-990c-000000000000 2m3.271518964s
[tsm1wal] 2016/05/16 18:33:50 tsm1 WAL starting with 10485760 segment size
[tsm1wal] 2016/05/16 18:33:50 tsm1 WAL writing to /var/lib/influxdb/wal/server/default/83509
[tsm1] 2016/05/16 18:33:50 /var/lib/influxdb/data/server/default/83509 database index loaded in 1.226µs
[http] 2016/05/16 18:33:50 172.31.17.25 - dashboard [16/May/2016:18:31:47 +0000] POST /write?db=server&precision=ms HTTP/1.1 204 0 - AHC/1.0 726ec931-1b94-11e6-98ee-000000000000 2m3.890895631s
[retention] 2016/05/16 18:33:51 shard ID 82311 from database server, retention policy default, deleted

@francisdb francisdb changed the title A lot of writes outside of /data and /wal Slow writes / memory peaks / sudden crahes for small db May 16, 2016
@jwilder
Copy link
Contributor

jwilder commented May 16, 2016

The profiles show many writes blocking trying to create shard groups which is strange. Looking at the code, there is some optimization that could be done there, but the heap dumps show quite a lot of memory in use for shard groups.

How many shards do you have and how are they configured? Did you change shard duration or any other settings from the defaults?

@francisdb
Copy link
Author

francisdb commented May 16, 2016

Nope, have always used default config settings
1119 folders in /data
1016 of those folders are empty (all higher numbers)
116 tsm files in those folders

@jwilder
Copy link
Contributor

jwilder commented May 16, 2016

@francisdb Can you provide the output of show shards?

@francisdb
Copy link
Author

> show shards
name: _internal
---------------
id  database    retention_policy    shard_group start_time      end_time        expiry_time     owners
3117    _internal   monitor         3117        2016-05-09T00:00:00Z    2016-05-10T00:00:00Z    2016-05-17T00:00:00Z    1
4027    _internal   monitor         4027        2016-05-10T00:00:00Z    2016-05-11T00:00:00Z    2016-05-18T00:00:00Z    1
5948    _internal   monitor         5948        2016-05-11T00:00:00Z    2016-05-12T00:00:00Z    2016-05-19T00:00:00Z    1
14847   _internal   monitor         14847       2016-05-12T00:00:00Z    2016-05-13T00:00:00Z    2016-05-20T00:00:00Z    1
26761   _internal   monitor         26761       2016-05-13T00:00:00Z    2016-05-14T00:00:00Z    2016-05-21T00:00:00Z    1
43800   _internal   monitor         43800       2016-05-14T00:00:00Z    2016-05-15T00:00:00Z    2016-05-22T00:00:00Z    1
67375   _internal   monitor         67375       2016-05-15T00:00:00Z    2016-05-16T00:00:00Z    2016-05-23T00:00:00Z    1
77992   _internal   monitor         77992       2016-05-16T00:00:00Z    2016-05-17T00:00:00Z    2016-05-24T00:00:00Z


name: mydatabase3
----------------------
id  database    retention_policy    shard_group start_time  end_time    expiry_time owners


name: mydatabase2
----------------------
id  database    retention_policy    shard_group start_time  end_time    expiry_time owners


name: mydatabase
------------------------------------
id  database            retention_policy    shard_group start_time      end_time        expiry_time     owners
84451   mydatabase  default         84451       2014-10-01T00:00:00Z    2014-10-02T00:00:00Z    2014-10-30T00:00:00Z
84445   mydatabase  default         84445       2014-10-02T00:00:00Z    2014-10-03T00:00:00Z    2014-10-31T00:00:00Z
84432   mydatabase  default         84432       2014-10-03T00:00:00Z    2014-10-04T00:00:00Z    2014-11-01T00:00:00Z
84450   mydatabase  default         84450       2014-10-04T00:00:00Z    2014-10-05T00:00:00Z    2014-11-02T00:00:00Z
84415   mydatabase  default         84415       2014-10-05T00:00:00Z    2014-10-06T00:00:00Z    2014-11-03T00:00:00Z
84444   mydatabase  default         84444       2014-10-06T00:00:00Z    2014-10-07T00:00:00Z    2014-11-04T00:00:00Z
84414   mydatabase  default         84414       2014-10-07T00:00:00Z    2014-10-08T00:00:00Z    2014-11-05T00:00:00Z
84425   mydatabase  default         84425       2014-10-08T00:00:00Z    2014-10-09T00:00:00Z    2014-11-06T00:00:00Z
84441   mydatabase  default         84441       2014-10-09T00:00:00Z    2014-10-10T00:00:00Z    2014-11-07T00:00:00Z
84428   mydatabase  default         84428       2014-10-10T00:00:00Z    2014-10-11T00:00:00Z    2014-11-08T00:00:00Z
84431   mydatabase  default         84431       2014-10-11T00:00:00Z    2014-10-12T00:00:00Z    2014-11-09T00:00:00Z
84439   mydatabase  default         84439       2014-10-12T00:00:00Z    2014-10-13T00:00:00Z    2014-11-10T00:00:00Z
84424   mydatabase  default         84424       2014-10-13T00:00:00Z    2014-10-14T00:00:00Z    2014-11-11T00:00:00Z
84423   mydatabase  default         84423       2014-10-14T00:00:00Z    2014-10-15T00:00:00Z    2014-11-12T00:00:00Z
84413   mydatabase  default         84413       2014-10-15T00:00:00Z    2014-10-16T00:00:00Z    2014-11-13T00:00:00Z
84419   mydatabase  default         84419       2014-10-16T00:00:00Z    2014-10-17T00:00:00Z    2014-11-14T00:00:00Z
84443   mydatabase  default         84443       2014-10-17T00:00:00Z    2014-10-18T00:00:00Z    2014-11-15T00:00:00Z
84440   mydatabase  default         84440       2014-10-18T00:00:00Z    2014-10-19T00:00:00Z    2014-11-16T00:00:00Z
84418   mydatabase  default         84418       2014-10-19T00:00:00Z    2014-10-20T00:00:00Z    2014-11-17T00:00:00Z
84430   mydatabase  default         84430       2014-10-20T00:00:00Z    2014-10-21T00:00:00Z    2014-11-18T00:00:00Z
84417   mydatabase  default         84417       2014-10-21T00:00:00Z    2014-10-22T00:00:00Z    2014-11-19T00:00:00Z
84449   mydatabase  default         84449       2014-10-22T00:00:00Z    2014-10-23T00:00:00Z    2014-11-20T00:00:00Z
84427   mydatabase  default         84427       2014-10-23T00:00:00Z    2014-10-24T00:00:00Z    2014-11-21T00:00:00Z
84426   mydatabase  default         84426       2014-10-24T00:00:00Z    2014-10-25T00:00:00Z    2014-11-22T00:00:00Z
84416   mydatabase  default         84416       2014-10-25T00:00:00Z    2014-10-26T00:00:00Z    2014-11-23T00:00:00Z
84442   mydatabase  default         84442       2014-10-26T00:00:00Z    2014-10-27T00:00:00Z    2014-11-24T00:00:00Z
84422   mydatabase  default         84422       2014-10-27T00:00:00Z    2014-10-28T00:00:00Z    2014-11-25T00:00:00Z
84421   mydatabase  default         84421       2014-10-28T00:00:00Z    2014-10-29T00:00:00Z    2014-11-26T00:00:00Z
84420   mydatabase  default         84420       2014-10-29T00:00:00Z    2014-10-30T00:00:00Z    2014-11-27T00:00:00Z
84438   mydatabase  default         84438       2014-10-30T00:00:00Z    2014-10-31T00:00:00Z    2014-11-28T00:00:00Z
84448   mydatabase  default         84448       2014-10-31T00:00:00Z    2014-11-01T00:00:00Z    2014-11-29T00:00:00Z
84429   mydatabase  default         84429       2014-11-01T00:00:00Z    2014-11-02T00:00:00Z    2014-11-30T00:00:00Z
84436   mydatabase  default         84436       2014-11-02T00:00:00Z    2014-11-03T00:00:00Z    2014-12-01T00:00:00Z
84435   mydatabase  default         84435       2014-11-03T00:00:00Z    2014-11-04T00:00:00Z    2014-12-02T00:00:00Z
84434   mydatabase  default         84434       2014-11-04T00:00:00Z    2014-11-05T00:00:00Z    2014-12-03T00:00:00Z
84437   mydatabase  default         84437       2014-11-05T00:00:00Z    2014-11-06T00:00:00Z    2014-12-04T00:00:00Z
84447   mydatabase  default         84447       2014-11-06T00:00:00Z    2014-11-07T00:00:00Z    2014-12-05T00:00:00Z
84446   mydatabase  default         84446       2014-11-07T00:00:00Z    2014-11-08T00:00:00Z    2014-12-06T00:00:00Z
84433   mydatabase  default         84433       2014-11-08T00:00:00Z    2014-11-09T00:00:00Z    2014-12-07T00:00:00Z
1383    mydatabase  default         1383        2016-04-18T00:00:00Z    2016-04-19T00:00:00Z    2016-05-17T00:00:00Z    1
1385    mydatabase  default         1385        2016-04-19T00:00:00Z    2016-04-20T00:00:00Z    2016-05-18T00:00:00Z    1
1452    mydatabase  default         1452        2016-04-20T00:00:00Z    2016-04-21T00:00:00Z    2016-05-19T00:00:00Z    1
1584    mydatabase  default         1584        2016-04-21T00:00:00Z    2016-04-22T00:00:00Z    2016-05-20T00:00:00Z    1
1651    mydatabase  default         1651        2016-04-22T00:00:00Z    2016-04-23T00:00:00Z    2016-05-21T00:00:00Z    1
1977    mydatabase  default         1977        2016-04-23T00:00:00Z    2016-04-24T00:00:00Z    2016-05-22T00:00:00Z    1
2109    mydatabase  default         2109        2016-04-24T00:00:00Z    2016-04-25T00:00:00Z    2016-05-23T00:00:00Z    1
2176    mydatabase  default         2176        2016-04-25T00:00:00Z    2016-04-26T00:00:00Z    2016-05-24T00:00:00Z    1
2438    mydatabase  default         2438        2016-04-26T00:00:00Z    2016-04-27T00:00:00Z    2016-05-25T00:00:00Z    1
2570    mydatabase  default         2570        2016-04-27T00:00:00Z    2016-04-28T00:00:00Z    2016-05-26T00:00:00Z    1
2705    mydatabase  default         2705        2016-04-28T00:00:00Z    2016-04-29T00:00:00Z    2016-05-27T00:00:00Z    1
2840    mydatabase  default         2840        2016-04-29T00:00:00Z    2016-04-30T00:00:00Z    2016-05-28T00:00:00Z    1
2907    mydatabase  default         2907        2016-04-30T00:00:00Z    2016-05-01T00:00:00Z    2016-05-29T00:00:00Z    1
2943    mydatabase  default         2943        2016-05-01T00:00:00Z    2016-05-02T00:00:00Z    2016-05-30T00:00:00Z    1
2945    mydatabase  default         2945        2016-05-02T00:00:00Z    2016-05-03T00:00:00Z    2016-05-31T00:00:00Z    1
2976    mydatabase  default         2976        2016-05-03T00:00:00Z    2016-05-04T00:00:00Z    2016-06-01T00:00:00Z    1
2978    mydatabase  default         2978        2016-05-04T00:00:00Z    2016-05-05T00:00:00Z    2016-06-02T00:00:00Z    1
3110    mydatabase  default         3110        2016-05-05T00:00:00Z    2016-05-06T00:00:00Z    2016-06-03T00:00:00Z    1
3112    mydatabase  default         3112        2016-05-06T00:00:00Z    2016-05-07T00:00:00Z    2016-06-04T00:00:00Z    1
3114    mydatabase  default         3114        2016-05-07T00:00:00Z    2016-05-08T00:00:00Z    2016-06-05T00:00:00Z    1
3116    mydatabase  default         3116        2016-05-08T00:00:00Z    2016-05-09T00:00:00Z    2016-06-06T00:00:00Z    1
3118    mydatabase  default         3118        2016-05-09T00:00:00Z    2016-05-10T00:00:00Z    2016-06-07T00:00:00Z    1
4028    mydatabase  default         4028        2016-05-10T00:00:00Z    2016-05-11T00:00:00Z    2016-06-08T00:00:00Z    1
5949    mydatabase  default         5949        2016-05-11T00:00:00Z    2016-05-12T00:00:00Z    2016-06-09T00:00:00Z    1
14848   mydatabase  default         14848       2016-05-12T00:00:00Z    2016-05-13T00:00:00Z    2016-06-10T00:00:00Z    1
26794   mydatabase  default         26794       2016-05-13T00:00:00Z    2016-05-14T00:00:00Z    2016-06-11T00:00:00Z    1
44196   mydatabase  default         44196       2016-05-14T00:00:00Z    2016-05-15T00:00:00Z    2016-06-12T00:00:00Z    1
67768   mydatabase  default         67768       2016-05-15T00:00:00Z    2016-05-16T00:00:00Z    2016-06-13T00:00:00Z    1
77993   mydatabase  default         77993       2016-05-16T00:00:00Z    2016-05-17T00:00:00Z    2016-06-14T00:00:00Z

@francisdb
Copy link
Author

This is a fresh machine from this morning where I imported mydatabase

@jwilder jwilder added this to the 1.0.0 milestone May 16, 2016
@francisdb
Copy link
Author

francisdb commented May 16, 2016

@jwilder thanks for looking into this.

Is there anything we can do to make things better? Our server crashes many times per day. It sometimes takes a few minutes time to restart.

I will also have a look why we end up with these 2014-11-04 shards, I guess that means we have written data that is going to be discarded anyway.

@francisdb
Copy link
Author

Replies on questions from the mailing list

What's the time range and/or number of points sampled for the queries? How many GROUP BY buckets?

We have this problem also at night when there are 0 queries performed, only data added. Could this be related to overwriting existing data?
What we do is that we sometimes get data for the last hour every 15 min from an external system and we write that to influx. So there is an overlap of 45 min of data every time. (limited amount of points though)
(I guess we might also be seeing #6557 or #6611)

Are your writes mostly sequential timestamps or are you writing points in a random order?
Could be that they are random order, need to check

CPU use is constantly ~20% with peaks of 100%
Nothing suspicious about that. Wouldn't you expect the database to occasionally use up a full core or more?

We constantly have 4 cores at ~25% and longer 50+% peaks on all cores

We need more data, like actual examples of your line protocol writes, and your queries.

I will try to upload a log today and am currently reviewing the writes

@francisdb
Copy link
Author

Some logs we got just now during a memory spike:

[tsm1] 2016/05/17 08:35:34 /var/lib/influxdb/data/mydatabase/default/90069 database index loaded in 1.43µs
[http] 2016/05/17 08:35:34 172.31.17.25 - dashboard [17/May/2016:08:35:03 +0000] POST /write?db=mydatabase&precision=ms HTTP/1.1 204 0 - AHC/1.0 3fff913f-1c0a-11e6-bd90-000000000000 31.6986226s
[query] 2016/05/17 08:35:35 SHOW SHARDS
[http] 2016/05/17 08:35:35 127.0.0.1 - dashboard [17/May/2016:08:35:28 +0000] GET /query?chunked=true&db=&epoch=ns&q=show+shards HTTP/1.1 200 878 - InfluxDBShell/0.12.2 4ef01584-1c0a-11e6-be13-000000000000 7.018359428s
[tsm1wal] 2016/05/17 08:35:35 tsm1 WAL starting with 10485760 segment size
[tsm1wal] 2016/05/17 08:35:35 tsm1 WAL writing to /var/lib/influxdb/wal/mydatabase/default/90070
[tsm1] 2016/05/17 08:35:35 /var/lib/influxdb/data/mydatabase/default/90070 database index loaded in 1.583µs
[http] 2016/05/17 08:35:35 172.31.17.25 - dashboard [17/May/2016:08:34:57 +0000] POST /write?db=mydatabase&precision=ms HTTP/1.1 204 0 - AHC/1.0 3c749c1e-1c0a-11e6-bc3e-000000000000 38.204106957s
[tsm1wal] 2016/05/17 08:35:35 tsm1 WAL starting with 10485760 segment size
[tsm1wal] 2016/05/17 08:35:35 tsm1 WAL writing to /var/lib/influxdb/wal/mydatabase/default/90071
[tsm1] 2016/05/17 08:35:35 /var/lib/influxdb/data/mydatabase/default/90071 database index loaded in 1.454µs
[http] 2016/05/17 08:35:35 172.31.17.25 - dashboard [17/May/2016:08:35:07 +0000] POST /write?db=mydatabase&precision=ms HTTP/1.1 204 0 - AHC/1.0 42ca805d-1c0a-11e6-be0f-000000000000 27.688273619s
[tsm1wal] 2016/05/17 08:35:35 tsm1 WAL starting with 10485760 segment size
[tsm1wal] 2016/05/17 08:35:35 tsm1 WAL writing to /var/lib/influxdb/wal/mydatabase/default/90072
[tsm1] 2016/05/17 08:35:35 /var/lib/influxdb/data/mydatabase/default/90072 database index loaded in 1.74µs
[http] 2016/05/17 08:35:35 172.31.17.25 - dashboard [17/May/2016:08:34:57 +0000] POST /write?db=mydatabase&precision=ms HTTP/1.1 204 0 - AHC/1.0 3c6e6d9b-1c0a-11e6-bc35-000000000000 38.560855681s
[http] 2016/05/17 08:35:35 172.31.17.25 - dashboard [17/May/2016:08:34:56 +0000] POST /write?db=mydatabase&precision=ms HTTP/1.1 204 0 - AHC/1.0 3c4282cc-1c0a-11e6-bc1e-000000000000 38.881386147s
[tsm1wal] 2016/05/17 08:35:35 tsm1 WAL starting with 10485760 segment size
[tsm1wal] 2016/05/17 08:35:35 tsm1 WAL writing to /var/lib/influxdb/wal/mydatabase/default/90073
[tsm1] 2016/05/17 08:35:35 /var/lib/influxdb/data/mydatabase/default/90073 database index loaded in 1.536µs
[http] 2016/05/17 08:35:35 172.31.17.25 - dashboard [17/May/2016:08:34:57 +0000] POST /write?db=mydatabase&precision=ms HTTP/1.1 204 0 - AHC/1.0 3c77b369-1c0a-11e6-bc44-000000000000 38.729900944s
[http] 2016/05/17 08:35:35 172.31.17.25 - dashboard [17/May/2016:08:34:56 +0000] POST /write?db=mydatabase&precision=ms HTTP/1.1 204 0 - AHC/1.0 3c40fc4e-1c0a-11e6-bc1b-000000000000 39.133117268s
[tsm1wal] 2016/05/17 08:35:36 tsm1 WAL starting with 10485760 segment size
[tsm1wal] 2016/05/17 08:35:36 tsm1 WAL writing to /var/lib/influxdb/wal/mydatabase/default/90074
[tsm1] 2016/05/17 08:35:36 /var/lib/influxdb/data/mydatabase/default/90074 database index loaded in 1.564µs
[http] 2016/05/17 08:35:36 172.31.17.25 - dashboard [17/May/2016:08:34:57 +0000] POST /write?db=mydatabase&precision=ms HTTP/1.1 204 0 - AHC/1.0 3c77b369-1c0a-11e6-bc43-000000000000 39.017715086s
[tsm1wal] 2016/05/17 08:35:36 tsm1 WAL starting with 10485760 segment size
[tsm1wal] 2016/05/17 08:35:36 tsm1 WAL writing to /var/lib/influxdb/wal/mydatabase/default/90075
[tsm1] 2016/05/17 08:35:36 /var/lib/influxdb/data/mydatabase/default/90075 database index loaded in 1.644µs
[http] 2016/05/17 08:35:36 172.31.17.25 - dashboard [17/May/2016:08:34:56 +0000] POST /write?db=mydatabase&precision=ms HTTP/1.1 204 0 - AHC/1.0 3c4c8aab-1c0a-11e6-bc27-000000000000 39.539678807s
[tsm1wal] 2016/05/17 08:35:36 tsm1 WAL starting with 10485760 segment size
[tsm1wal] 2016/05/17 08:35:36 tsm1 WAL writing to /var/lib/influxdb/wal/mydatabase/default/90076
[tsm1] 2016/05/17 08:35:36 /var/lib/influxdb/data/mydatabase/default/90076 database index loaded in 2.376µs
[http] 2016/05/17 08:35:36 172.31.17.25 - dashboard [17/May/2016:08:34:57 +0000] POST /write?db=mydatabase&precision=ms HTTP/1.1 204 0 - AHC/1.0 3cb725c3-1c0a-11e6-bc61-000000000000 39.161561861s
[tsm1wal] 2016/05/17 08:35:39 tsm1 WAL starting with 10485760 segment size
[tsm1wal] 2016/05/17 08:35:39 tsm1 WAL writing to /var/lib/influxdb/wal/mydatabase/default/90077
[tsm1] 2016/05/17 08:35:39 /var/lib/influxdb/data/mydatabase/default/90077 database index loaded in 1.454µs
[http] 2016/05/17 08:35:39 172.31.17.25 - dashboard [17/May/2016:08:34:57 +0000] POST /write?db=mydatabase&precision=ms HTTP/1.1 204 0 - AHC/1.0 3c7de19a-1c0a-11e6-bc4a-000000000000 42.056314289s
[tsm1wal] 2016/05/17 08:35:39 tsm1 WAL starting with 10485760 segment size
[tsm1wal] 2016/05/17 08:35:39 tsm1 WAL writing to /var/lib/influxdb/wal/mydatabase/default/90078
[tsm1] 2016/05/17 08:35:39 /var/lib/influxdb/data/mydatabase/default/90078 database index loaded in 1.318µs
[http] 2016/05/17 08:35:39 172.31.17.25 - dashboard [17/May/2016:08:34:57 +0000] POST /write?db=mydatabase&precision=ms HTTP/1.1 204 0 - AHC/1.0 3c6bc1da-1c0a-11e6-bc31-000000000000 42.590310555s
[tsm1wal] 2016/05/17 08:35:40 tsm1 WAL starting with 10485760 segment size
[tsm1wal] 2016/05/17 08:35:40 tsm1 WAL writing to /var/lib/influxdb/wal/mydatabase/default/90079
[tsm1] 2016/05/17 08:35:40 /var/lib/influxdb/data/mydatabase/default/90079 database index loaded in 1.267µs
[http] 2016/05/17 08:35:40 172.31.17.25 - dashboard [17/May/2016:08:34:57 +0000] POST /write?db=mydatabase&precision=ms HTTP/1.1 204 0 - AHC/1.0 3c6bd35f-1c0a-11e6-bc34-000000000000 43.316534952s
[tsm1wal] 2016/05/17 08:35:40 tsm1 WAL starting with 10485760 segment size
[tsm1wal] 2016/05/17 08:35:40 tsm1 WAL writing to /var/lib/influxdb/wal/mydatabase/default/90080
[tsm1] 2016/05/17 08:35:40 /var/lib/influxdb/data/mydatabase/default/90080 database index loaded in 1.518µs
[http] 2016/05/17 08:35:40 172.31.17.25 - dashboard [17/May/2016:08:34:57 +0000] POST /write?db=mydatabase&precision=ms HTTP/1.1 204 0 - AHC/1.0 3c6bc9cd-1c0a-11e6-bc32-000000000000 43.392564668s
[tsm1wal] 2016/05/17 08:35:40 tsm1 WAL starting with 10485760 segment size
[tsm1wal] 2016/05/17 08:35:40 tsm1 WAL writing to /var/lib/influxdb/wal/mydatabase/default/90081
[tsm1] 2016/05/17 08:35:40 /var/lib/influxdb/data/mydatabase/default/90081 database index loaded in 1.502µs
[http] 2016/05/17 08:35:40 172.31.17.25 - dashboard [17/May/2016:08:34:57 +0000] POST /write?db=mydatabase&precision=ms HTTP/1.1 204 0 - AHC/1.0 3cd2f3f6-1c0a-11e6-bc86-000000000000 42.92971336s
[tsm1wal] 2016/05/17 08:35:40 tsm1 WAL starting with 10485760 segment size
[tsm1wal] 2016/05/17 08:35:40 tsm1 WAL writing to /var/lib/influxdb/wal/mydatabase/default/90082
[tsm1] 2016/05/17 08:35:40 /var/lib/influxdb/data/mydatabase/default/90082 database index loaded in 1.328µs
[http] 2016/05/17 08:35:40 172.31.17.25 - dashboard [17/May/2016:08:34:59 +0000] POST /write?db=mydatabase&precision=ms HTTP/1.1 204 0 - AHC/1.0 3d9ce402-1c0a-11e6-bcaf-000000000000 41.831222973s
[tsm1wal] 2016/05/17 08:35:41 tsm1 WAL starting with 10485760 segment size
[tsm1wal] 2016/05/17 08:35:41 tsm1 WAL writing to /var/lib/influxdb/wal/mydatabase/default/90083
[tsm1] 2016/05/17 08:35:41 /var/lib/influxdb/data/mydatabase/default/90083 database index loaded in 1.384µs
[http] 2016/05/17 08:35:41 172.31.17.25 - dashboard [17/May/2016:08:34:57 +0000] POST /write?db=mydatabase&precision=ms HTTP/1.1 204 0 - AHC/1.0 3c749c1e-1c0a-11e6-bc3d-000000000000 44.006441216s
[tsm1wal] 2016/05/17 08:35:41 tsm1 WAL starting with 10485760 segment size
[tsm1wal] 2016/05/17 08:35:41 tsm1 WAL writing to /var/lib/influxdb/wal/mydatabase/default/90084
[tsm1] 2016/05/17 08:35:41 /var/lib/influxdb/data/mydatabase/default/90084 database index loaded in 1.562µs
[http] 2016/05/17 08:35:41 172.31.17.25 - dashboard [17/May/2016:08:34:57 +0000] POST /write?db=mydatabase&precision=ms HTTP/1.1 204 0 - AHC/1.0 3c80f902-1c0a-11e6-bc4d-000000000000 44.147892045s
[tsm1wal] 2016/05/17 08:35:41 tsm1 WAL starting with 10485760 segment size
[tsm1wal] 2016/05/17 08:35:41 tsm1 WAL writing to /var/lib/influxdb/wal/mydatabase/default/90085
[tsm1] 2016/05/17 08:35:41 /var/lib/influxdb/data/mydatabase/default/90085 database index loaded in 1.336µs
[http] 2016/05/17 08:35:41 172.31.17.25 - dashboard [17/May/2016:08:34:57 +0000] POST /write?db=mydatabase&precision=ms HTTP/1.1 204 0 - AHC/1.0 3cb1f66d-1c0a-11e6-bc5b-000000000000 44.107628656s
[tsm1wal] 2016/05/17 08:35:41 tsm1 WAL starting with 10485760 segment size
[tsm1wal] 2016/05/17 08:35:41 tsm1 WAL writing to /var/lib/influxdb/wal/mydatabase/default/90086
[tsm1] 2016/05/17 08:35:41 /var/lib/influxdb/data/mydatabase/default/90086 database index loaded in 1.302µs
[http] 2016/05/17 08:35:41 172.31.17.25 - dashboard [17/May/2016:08:34:57 +0000] POST /write?db=mydatabase&precision=ms HTTP/1.1 204 0 - AHC/1.0 3ce2661a-1c0a-11e6-bc96-000000000000 44.006471331s
[tsm1wal] 2016/05/17 08:35:42 tsm1 WAL starting with 10485760 segment size
[tsm1wal] 2016/05/17 08:35:42 tsm1 WAL writing to /var/lib/influxdb/wal/mydatabase/default/90087
[tsm1] 2016/05/17 08:35:42 /var/lib/influxdb/data/mydatabase/default/90087 database index loaded in 1.275µs
[http] 2016/05/17 08:35:42 172.31.17.25 - dashboard [17/May/2016:08:34:56 +0000] POST /write?db=mydatabase&precision=ms HTTP/1.1 204 0 - AHC/1.0 3c4e188b-1c0a-11e6-bc28-000000000000 45.210753799s
[tsm1wal] 2016/05/17 08:35:42 tsm1 WAL starting with 10485760 segment size
[tsm1wal] 2016/05/17 08:35:42 tsm1 WAL writing to /var/lib/influxdb/wal/mydatabase/default/90088
[tsm1] 2016/05/17 08:35:42 /var/lib/influxdb/data/mydatabase/default/90088 database index loaded in 1.322µs
[http] 2016/05/17 08:35:42 172.31.17.25 - dashboard [17/May/2016:08:34:57 +0000] POST /write?db=mydatabase&precision=ms HTTP/1.1 204 0 - AHC/1.0 3c749c1e-1c0a-11e6-bc3f-000000000000 45.177116628s
[http] 2016/05/17 08:35:42 172.31.17.25 - dashboard [17/May/2016:08:34:56 +0000] POST /write?db=mydatabase&precision=ms HTTP/1.1 204 0 - AHC/1.0 3c427c65-1c0a-11e6-bc1d-000000000000 45.552473497s
[tsm1wal] 2016/05/17 08:35:42 tsm1 WAL starting with 10485760 segment size
[tsm1wal] 2016/05/17 08:35:42 tsm1 WAL writing to /var/lib/influxdb/wal/mydatabase/default/90089
[tsm1] 2016/05/17 08:35:42 /var/lib/influxdb/data/mydatabase/default/90089 database index loaded in 1.513µs
[http] 2016/05/17 08:35:42 172.31.17.25 - dashboard [17/May/2016:08:34:57 +0000] POST /write?db=mydatabase&precision=ms HTTP/1.1 204 0 - AHC/1.0 3cd2f182-1c0a-11e6-bc84-000000000000 44.862829551s
[tsm1wal] 2016/05/17 08:35:42 tsm1 WAL starting with 10485760 segment size
[tsm1wal] 2016/05/17 08:35:42 tsm1 WAL writing to /var/lib/influxdb/wal/mydatabase/default/90090
[tsm1] 2016/05/17 08:35:42 /var/lib/influxdb/data/mydatabase/default/90090 database index loaded in 1.44µs
[http] 2016/05/17 08:35:42 172.31.17.25 - dashboard [17/May/2016:08:34:57 +0000] POST /write?db=mydatabase&precision=ms HTTP/1.1 204 0 - AHC/1.0 3c6bd336-1c0a-11e6-bc33-000000000000 45.761279243s
[http] 2016/05/17 08:35:42 172.31.17.25 - dashboard [17/May/2016:08:34:57 +0000] POST /write?db=mydatabase&precision=ms HTTP/1.1 204 0 - AHC/1.0 3ce57d4b-1c0a-11e6-bc98-000000000000 45.046859956s
[tsm1wal] 2016/05/17 08:35:43 tsm1 WAL starting with 10485760 segment size
[tsm1wal] 2016/05/17 08:35:43 tsm1 WAL writing to /var/lib/influxdb/wal/mydatabase/default/90091
[tsm1] 2016/05/17 08:35:43 /var/lib/influxdb/data/mydatabase/default/90091 database index loaded in 1.403µs
[http] 2016/05/17 08:35:43 172.31.17.25 - dashboard [17/May/2016:08:35:41 +0000] POST /write?db=mydatabase&precision=ms HTTP/1.1 204 0 - AHC/1.0 571c7b9d-1c0a-11e6-be25-000000000000 1.316469049s
[tsm1wal] 2016/05/17 08:35:43 tsm1 WAL starting with 10485760 segment size
[tsm1wal] 2016/05/17 08:35:43 tsm1 WAL writing to /var/lib/influxdb/wal/mydatabase/default/90092
[tsm1] 2016/05/17 08:35:43 /var/lib/influxdb/data/mydatabase/default/90092 database index loaded in 1.333µs
[http] 2016/05/17 08:35:43 172.31.17.25 - dashboard [17/May/2016:08:34:57 +0000] POST /write?db=mydatabase&precision=ms HTTP/1.1 204 0 - AHC/1.0 3cebab6d-1c0a-11e6-bc9c-000000000000 45.488664232s
[tsm1wal] 2016/05/17 08:35:43 tsm1 WAL starting with 10485760 segment size
[tsm1wal] 2016/05/17 08:35:43 tsm1 WAL writing to /var/lib/influxdb/wal/mydatabase/default/90093
[tsm1] 2016/05/17 08:35:43 /var/lib/influxdb/data/mydatabase/default/90093 database index loaded in 1.289µs
[http] 2016/05/17 08:35:43 172.31.17.25 - dashboard [17/May/2016:08:34:57 +0000] POST /write?db=mydatabase&precision=ms HTTP/1.1 204 0 - AHC/1.0 3ccfdd20-1c0a-11e6-bc81-000000000000 45.944202785s
[tsm1wal] 2016/05/17 08:35:43 tsm1 WAL starting with 10485760 segment size
[tsm1wal] 2016/05/17 08:35:43 tsm1 WAL writing to /var/lib/influxdb/wal/mydatabase/default/90094
[tsm1] 2016/05/17 08:35:43 /var/lib/influxdb/data/mydatabase/default/90094 database index loaded in 1.536µs
[http] 2016/05/17 08:35:43 172.31.17.25 - dashboard [17/May/2016:08:34:57 +0000] POST /write?db=mydatabase&precision=ms HTTP/1.1 204 0 - AHC/1.0 3c718513-1c0a-11e6-bc3a-000000000000 46.898078292s
[tsm1wal] 2016/05/17 08:35:44 tsm1 WAL starting with 10485760 segment size
[tsm1wal] 2016/05/17 08:35:44 tsm1 WAL writing to /var/lib/influxdb/wal/mydatabase/default/90095
[tsm1] 2016/05/17 08:35:44 /var/lib/influxdb/data/mydatabase/default/90095 database index loaded in 1.419µs
[http] 2016/05/17 08:35:44 172.31.17.25 - dashboard [17/May/2016:08:34:57 +0000] POST /write?db=mydatabase&precision=ms HTTP/1.1 204 0 - AHC/1.0 3cbd51f4-1c0a-11e6-bc6a-000000000000 46.689154164s
[tsm1wal] 2016/05/17 08:35:44 tsm1 WAL starting with 10485760 segment size
[tsm1wal] 2016/05/17 08:35:44 tsm1 WAL writing to /var/lib/influxdb/wal/mydatabase/default/90096
[tsm1] 2016/05/17 08:35:44 /var/lib/influxdb/data/mydatabase/default/90096 database index loaded in 1.936µs
[http] 2016/05/17 08:35:44 172.31.17.25 - dashboard [17/May/2016:08:34:56 +0000] POST /write?db=mydatabase&precision=ms HTTP/1.1 204 0 - AHC/1.0 3c4afef3-1c0a-11e6-bc26-000000000000 47.689840152s
[tsm1wal] 2016/05/17 08:35:44 tsm1 WAL starting with 10485760 segment size
[tsm1wal] 2016/05/17 08:35:44 tsm1 WAL writing to /var/lib/influxdb/wal/mydatabase/default/90097
[tsm1] 2016/05/17 08:35:44 /var/lib/influxdb/data/mydatabase/default/90097 database index loaded in 1.354µs
[http] 2016/05/17 08:35:44 172.31.17.25 - dashboard [17/May/2016:08:34:57 +0000] POST /write?db=mydatabase&precision=ms HTTP/1.1 204 0 - AHC/1.0 3cb725c0-1c0a-11e6-bc60-000000000000 47.295521762s
[tsm1wal] 2016/05/17 08:35:45 tsm1 WAL starting with 10485760 segment size
[tsm1wal] 2016/05/17 08:35:45 tsm1 WAL writing to /var/lib/influxdb/wal/mydatabase/default/90098
[tsm1] 2016/05/17 08:35:45 /var/lib/influxdb/data/mydatabase/default/90098 database index loaded in 1.836µs
[http] 2016/05/17 08:35:45 172.31.17.25 - dashboard [17/May/2016:08:34:57 +0000] POST /write?db=mydatabase&precision=ms HTTP/1.1 204 0 - AHC/1.0 3cdc3816-1c0a-11e6-bc8f-000000000000 47.572783851s
[tsm1wal] 2016/05/17 08:35:45 tsm1 WAL starting with 10485760 segment size
[tsm1wal] 2016/05/17 08:35:45 tsm1 WAL writing to /var/lib/influxdb/wal/mydatabase/default/90099
[tsm1] 2016/05/17 08:35:45 /var/lib/influxdb/data/mydatabase/default/90099 database index loaded in 1.648µs
[http] 2016/05/17 08:35:45 172.31.17.25 - dashboard [17/May/2016:08:34:57 +0000] POST /write?db=mydatabase&precision=ms HTTP/1.1 204 0 - AHC/1.0 3c7de19a-1c0a-11e6-bc49-000000000000 48.344024718s
[tsm1wal] 2016/05/17 08:35:45 tsm1 WAL starting with 10485760 segment size
[tsm1wal] 2016/05/17 08:35:45 tsm1 WAL writing to /var/lib/influxdb/wal/mydatabase/default/90100
[tsm1] 2016/05/17 08:35:45 /var/lib/influxdb/data/mydatabase/default/90100 database index loaded in 1.707µs
[http] 2016/05/17 08:35:45 172.31.17.25 - dashboard [17/May/2016:08:34:57 +0000] POST /write?db=mydatabase&precision=ms HTTP/1.1 204 0 - AHC/1.0 3cc696e4-1c0a-11e6-bc76-000000000000 48.120501758s
[tsm1wal] 2016/05/17 08:35:45 tsm1 WAL starting with 10485760 segment size
[tsm1wal] 2016/05/17 08:35:45 tsm1 WAL writing to /var/lib/influxdb/wal/mydatabase/default/90101
[tsm1] 2016/05/17 08:35:45 /var/lib/influxdb/data/mydatabase/default/90101 database index loaded in 1.705µs
[http] 2016/05/17 08:35:45 172.31.17.25 - dashboard [17/May/2016:08:34:57 +0000] POST /write?db=mydatabase&precision=ms HTTP/1.1 204 0 - AHC/1.0 3cb1554d-1c0a-11e6-bc58-000000000000 48.508750316s
[http] 2016/05/17 08:35:46 172.31.17.25 - dashboard [17/May/2016:08:34:57 +0000] POST /write?db=mydatabase&precision=ms HTTP/1.1 204 0 - AHC/1.0 3c6e6d9b-1c0a-11e6-bc38-000000000000 49.008309435s
[tsm1wal] 2016/05/17 08:35:46 tsm1 WAL starting with 10485760 segment size
[tsm1wal] 2016/05/17 08:35:46 tsm1 WAL writing to /var/lib/influxdb/wal/mydatabase/default/90102
[tsm1] 2016/05/17 08:35:46 /var/lib/influxdb/data/mydatabase/default/90102 database index loaded in 2.138µs
[http] 2016/05/17 08:35:46 172.31.17.25 - dashboard [17/May/2016:08:34:57 +0000] POST /write?db=mydatabase&precision=ms HTTP/1.1 204 0 - AHC/1.0 3cc06a24-1c0a-11e6-bc6f-000000000000 48.740931595s
[tsm1wal] 2016/05/17 08:35:46 tsm1 WAL starting with 10485760 segment size
[tsm1wal] 2016/05/17 08:35:46 tsm1 WAL writing to /var/lib/influxdb/wal/mydatabase/default/90103
[tsm1] 2016/05/17 08:35:46 /var/lib/influxdb/data/mydatabase/default/90103 database index loaded in 1.421µs
[http] 2016/05/17 08:35:46 172.31.17.25 - dashboard [17/May/2016:08:34:57 +0000] POST /write?db=mydatabase&precision=ms HTTP/1.1 204 0 - AHC/1.0 3c7aca87-1c0a-11e6-bc45-000000000000 49.447287877s
[tsm1wal] 2016/05/17 08:35:46 tsm1 WAL starting with 10485760 segment size
[tsm1wal] 2016/05/17 08:35:46 tsm1 WAL writing to /var/lib/influxdb/wal/mydatabase/default/90104
[tsm1] 2016/05/17 08:35:46 /var/lib/influxdb/data/mydatabase/default/90104 database index loaded in 1.463µs
[http] 2016/05/17 08:35:46 172.31.17.25 - dashboard [17/May/2016:08:34:58 +0000] POST /write?db=mydatabase&precision=ms HTTP/1.1 204 0 - AHC/1.0 3d685947-1c0a-11e6-bca6-000000000000 48.13125s
[tsm1wal] 2016/05/17 08:35:47 tsm1 WAL starting with 10485760 segment size
[tsm1wal] 2016/05/17 08:35:47 tsm1 WAL writing to /var/lib/influxdb/wal/mydatabase/default/90105
[tsm1] 2016/05/17 08:35:47 /var/lib/influxdb/data/mydatabase/default/90105 database index loaded in 1.318µs
[http] 2016/05/17 08:35:47 172.31.17.25 - dashboard [17/May/2016:08:34:57 +0000] POST /write?db=mydatabase&precision=ms HTTP/1.1 204 0 - AHC/1.0 3cba3ad9-1c0a-11e6-bc64-000000000000 49.528656853s
[tsm1wal] 2016/05/17 08:35:47 tsm1 WAL starting with 10485760 segment size
[tsm1wal] 2016/05/17 08:35:47 tsm1 WAL writing to /var/lib/influxdb/wal/mydatabase/default/90106
[tsm1] 2016/05/17 08:35:47 /var/lib/influxdb/data/mydatabase/default/90106 database index loaded in 1.023µs
[http] 2016/05/17 08:35:47 172.31.17.25 - dashboard [17/May/2016:08:34:56 +0000] POST /write?db=mydatabase&precision=ms HTTP/1.1 204 0 - AHC/1.0 3c63c6b0-1c0a-11e6-bc2b-000000000000 50.376398287s
[tsm1wal] 2016/05/17 08:35:47 tsm1 WAL starting with 10485760 segment size
[tsm1wal] 2016/05/17 08:35:47 tsm1 WAL writing to /var/lib/influxdb/wal/mydatabase/default/90107
[tsm1] 2016/05/17 08:35:47 /var/lib/influxdb/data/mydatabase/default/90107 database index loaded in 1.402µs
[http] 2016/05/17 08:35:47 172.31.17.25 - dashboard [17/May/2016:08:34:57 +0000] POST /write?db=mydatabase&precision=ms HTTP/1.1 204 0 - AHC/1.0 3c841020-1c0a-11e6-bc51-000000000000 50.465835425s
[tsm1wal] 2016/05/17 08:35:47 tsm1 WAL starting with 10485760 segment size
[tsm1wal] 2016/05/17 08:35:47 tsm1 WAL writing to /var/lib/influxdb/wal/mydatabase/default/90108
[tsm1] 2016/05/17 08:35:47 /var/lib/influxdb/data/mydatabase/default/90108 database index loaded in 1.45µs
[http] 2016/05/17 08:35:47 172.31.17.25 - dashboard [17/May/2016:08:34:57 +0000] POST /write?db=mydatabase&precision=ms HTTP/1.1 204 0 - AHC/1.0 3c77b368-1c0a-11e6-bc42-000000000000 50.875740229s
[tsm1wal] 2016/05/17 08:35:48 tsm1 WAL starting with 10485760 segment size
[tsm1wal] 2016/05/17 08:35:48 tsm1 WAL writing to /var/lib/influxdb/wal/mydatabase/default/90109
[tsm1] 2016/05/17 08:35:48 /var/lib/influxdb/data/mydatabase/default/90109 database index loaded in 1.318µs
[http] 2016/05/17 08:35:48 172.31.17.25 - dashboard [17/May/2016:08:34:56 +0000] POST /write?db=mydatabase&precision=ms HTTP/1.1 204 0 - AHC/1.0 3c49762f-1c0a-11e6-bc24-000000000000 51.535038847s
[http] 2016/05/17 08:35:48 172.31.17.25 - dashboard [17/May/2016:08:35:46 +0000] POST /write?db=mydatabase&precision=ms HTTP/1.1 204 0 - AHC/1.0 5a18a08e-1c0a-11e6-be39-000000000000 1.558171353s
[http] 2016/05/17 08:35:48 172.31.17.25 - dashboard [17/May/2016:08:34:56 +0000] POST /write?db=mydatabase&precision=ms HTTP/1.1 204 0 - AHC/1.0 3c426551-1c0a-11e6-bc1c-000000000000 51.698979528s
[tsm1wal] 2016/05/17 08:35:48 tsm1 WAL starting with 10485760 segment size
[tsm1wal] 2016/05/17 08:35:48 tsm1 WAL writing to /var/lib/influxdb/wal/mydatabase/default/90110
[tsm1] 2016/05/17 08:35:48 /var/lib/influxdb/data/mydatabase/default/90110 database index loaded in 1.582µs
[http] 2016/05/17 08:35:48 172.31.17.25 - dashboard [17/May/2016:08:34:57 +0000] POST /write?db=mydatabase&precision=ms HTTP/1.1 204 0 - AHC/1.0 3c718513-1c0a-11e6-bc3b-000000000000 51.646557319s
[tsm1wal] 2016/05/17 08:35:48 tsm1 WAL starting with 10485760 segment size
[tsm1wal] 2016/05/17 08:35:48 tsm1 WAL writing to /var/lib/influxdb/wal/mydatabase/default/90111
[tsm1] 2016/05/17 08:35:48 /var/lib/influxdb/data/mydatabase/default/90111 database index loaded in 1.272µs
[http] 2016/05/17 08:35:48 172.31.17.25 - dashboard [17/May/2016:08:34:57 +0000] POST /write?db=mydatabase&precision=ms HTTP/1.1 204 0 - AHC/1.0 3cb40e48-1c0a-11e6-bc5c-000000000000 51.448696395s
[http] 2016/05/17 08:35:48 172.31.17.25 - dashboard [17/May/2016:08:34:57 +0000] POST /write?db=mydatabase&precision=ms HTTP/1.1 204 0 - AHC/1.0 3cdf5014-1c0a-11e6-bc93-000000000000 51.197629106s
[http] 2016/05/17 08:35:49 172.31.17.25 - dashboard [17/May/2016:08:34:56 +0000] POST /write?db=mydatabase&precision=ms HTTP/1.1 204 0 - AHC/1.0 3c40f0f6-1c0a-11e6-bc19-000000000000 52.295297559s
[http] 2016/05/17 08:35:49 172.31.17.25 - dashboard [17/May/2016:08:34:58 +0000] POST /write?db=mydatabase&precision=ms HTTP/1.1 204 0 - AHC/1.0 3d654799-1c0a-11e6-bca5-000000000000 50.436372917s
[tsm1wal] 2016/05/17 08:35:49 tsm1 WAL starting with 10485760 segment size
[tsm1wal] 2016/05/17 08:35:49 tsm1 WAL writing to /var/lib/influxdb/wal/mydatabase/default/90112
[tsm1] 2016/05/17 08:35:49 /var/lib/influxdb/data/mydatabase/default/90112 database index loaded in 1.569µs
[http] 2016/05/17 08:35:49 172.31.17.25 - dashboard [17/May/2016:08:34:58 +0000] POST /write?db=mydatabase&precision=ms HTTP/1.1 204 0 - AHC/1.0 3d49aff9-1c0a-11e6-bca2-000000000000 50.851024105s
[tsm1wal] 2016/05/17 08:35:49 tsm1 WAL starting with 10485760 segment size
[tsm1wal] 2016/05/17 08:35:49 tsm1 WAL writing to /var/lib/influxdb/wal/mydatabase/default/90113
[tsm1] 2016/05/17 08:35:49 /var/lib/influxdb/data/mydatabase/default/90113 database index loaded in 1.429µs
[http] 2016/05/17 08:35:49 172.31.17.25 - dashboard [17/May/2016:08:34:57 +0000] POST /write?db=mydatabase&precision=ms HTTP/1.1 204 0 - AHC/1.0 3cc068d1-1c0a-11e6-bc6d-000000000000 51.956962649s
[run] 2016/05/17 08:35:49 Signal received, initializing clean shutdown...
[run] 2016/05/17 08:35:49 Waiting for clean shutdown...
[cluster] 2016/05/17 08:35:49 cluster service accept error: network connection closed
[snapshot] 2016/05/17 08:35:49 snapshot listener closed
[copier] 2016/05/17 08:35:49 copier listener closed
[shard-precreation] 2016/05/17 08:35:49 Precreation service terminating
[continuous_querier] 2016/05/17 08:35:49 continuous query service terminating
[retention] 2016/05/17 08:35:49 retention policy enforcement terminating
[monitor] 2016/05/17 08:35:49 shutting down monitor system
[run] 2016/05/17 08:35:49 second signal received, initializing hard shutdown
[run] 2016/05/17 08:35:49 InfluxDB starting, version 0.12.2, branch 0.12, commit 383332daed5595926c235f250b11433f67229c35
[run] 2016/05/17 08:35:49 Go version go1.4.3, GOMAXPROCS set to 4
[run] 2016/05/17 08:35:49 Using configuration at: /etc/influxdb/influxdb.conf
[store] 2016/05/17 08:35:50 Using data dir: /var/lib/influxdb/data
[tsm1wal] 2016/05/17 08:35:50 tsm1 WAL starting with 10485760 segment size
[tsm1wal] 2016/05/17 08:35:50 tsm1 WAL starting with 10485760 segment size
[tsm1wal] 2016/05/17 08:35:50 tsm1 WAL writing to /var/lib/influxdb/wal/_internal/monitor/77992
[tsm1wal] 2016/05/17 08:35:50 tsm1 WAL writing to /var/lib/influxdb/wal/_internal/monitor/86777

The restart is triggered by our own monitoring script that restarts influx when it goes over 92% ram.

@francisdb
Copy link
Author

francisdb commented May 17, 2016

I see we now have shards up to 2009-11-18, can a single datapoint uploaded for an old date start the creation of many shards?

We now have 425 shards, which on the next retention policy kicking in will be reduced to about 30!

@jwilder
Copy link
Contributor

jwilder commented May 17, 2016

There is a change in the current nightlies the reduced the lock contention around creating shard groups. You could try testing the latest nightly out to see if that helps.

@francisdb
Copy link
Author

@jwilder always a bit tricky to put a nightly in production...
What does it mean if a shard has no owners?
Tonight we had almost no problems, we had only 30 shards and all owners showing 1 except the most recent 3.
Now we again have a bunch of unowned shards, for times in the past further than the retention policy that appeared together with a small memory spike.

@francisdb
Copy link
Author

One more question, can we track the number of shards (groups) with telegraf?

@jwilder
Copy link
Contributor

jwilder commented May 18, 2016

@francisdb I thought you had a test env you could try the nightly on. My mistake.

Shard groups are created based on the time range of points. If you insert one point way in the past, it will cause a shard group for that time range to get created if it does not exist.

@francisdb
Copy link
Author

I think we found what is triggering our issues. We pull in data from other services and we had a webhook that told us to pull in data from way in the past. We now make sure we only pull in data that is not outside of the retention period.

In any way, I guess creating 100's of shards should not kill the server?

@francisdb
Copy link
Author

@jwilder to conclude:
we have a retention policy of 1 month
spread data uploads every 15 min
a few of those uploads were uploading ~1000 spread datapoints all the way back to 2010. This triggered the creation of a large amount of shards causing the whole system to go out of memory.

Should be easy to reproduce.

@francisdb francisdb changed the title Slow writes / memory peaks / sudden crahes for small db Slow writes / memory peaks / crashes caused by large amounts of shards creation May 19, 2016
@jwilder
Copy link
Contributor

jwilder commented May 23, 2016

@francisdb I logged #6708 which should hopefully prevent this situation from occurring in the future when fixed.

@jwilder jwilder closed this as completed May 23, 2016
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Projects
None yet
Development

No branches or pull requests

3 participants