Page MenuHomePhabricator

RESTBase performance testing
Closed, ResolvedPublic

Assigned To
Authored By
GWicke
Dec 1 2014, 7:15 PM
Referenced Files
F41256: pasted_file
Feb 14 2015, 9:41 PM
F41254: pasted_file
Feb 14 2015, 9:39 PM
F26649: pasted_file
Jan 5 2015, 7:51 PM
F21411: pasted_file
Dec 16 2014, 4:15 PM
F21300: pasted_file
Dec 15 2014, 7:14 PM

Description

  • perform a full dump of a large wiki through RESTBase, such as enwiki (can use a tool like https://github.com/gwicke/htmldumper) - done. Size for enwiki with lz4 compression: 70G html, 45G data-parsoid.
  • measure performance of reads in repeat run, after cassandra is filled
    • would be great to test response times with realistic traffic mix, possibly from the parsoid-lb service; look into getting logs from there & replaying those requests at high speed
  • could additionally resurrect the old wikitext dump import script (https://github.com/wikimedia/restbase-cassandra/tree/master/test/dump) & test a full wikitext import

Related Objects

View Standalone Graph
This task is connected to more than 200 other tasks. Only direct parents and subtasks are shown here. Use View Standalone Graph to show more of the graph.
StatusSubtypeAssignedTask
Resolved GWicke
Resolved GWicke

Event Timeline

GWicke raised the priority of this task from to Needs Triage.
GWicke updated the task description. (Show Details)
GWicke added a project: RESTBase.
GWicke changed Security from none to None.
GWicke subscribed.

Here are some first results and a graph:

  • On a 58kb HTML page, one node delivers about 1400req/s peak for a throughput close to the gbit limit (taking into account inter-node traffic in ganglia). This means that we should probably get 10gbit ethernet on the new, more powerful nodes to avoid bottlenecking on the network.
  • Internally recorded latency with some concurrent writes (about 10/s) and 200 concurrent readers per node remains << 100ms. The graph indicates 21ms at the 99th percentile. This is optimistic, as we are hitting a single URL for most reads, but it provides a good bottom line.
  • External latency as reported by ab -n 1000000 -c200 http://cerium.eqiad.wmnet:7231/v1/en.wikipedia.org/pages/Foobar/html/634702417 running against each of the three cluster nodes:
Document Length:        58240 bytes

Concurrency Level:      200
Time taken for tests:   1277.381 seconds
Complete requests:      1000000
Failed requests:        0
Write errors:           0
Total transferred:      58420000000 bytes
HTML transferred:       58240000000 bytes
Requests per second:    782.85 [#/sec] (mean)
Time per request:       255.476 [ms] (mean)
Time per request:       1.277 [ms] (mean, across all concurrent requests)
Transfer rate:          44662.29 [Kbytes/sec] received

Connection Times (ms)
              min  mean[+/-sd] median   max
Connect:        0  109 365.2      5   15037
Processing:     8  146 189.0     72   17964
Waiting:        8   66  66.4     49   14209
Total:          9  255 418.8     83   18969

Percentage of the requests served within a certain time (ms)
  50%     83
  66%    134
  75%    279
  80%    312
  90%    891
  95%   1094
  98%   1364
  99%   2163
 100%  18969 (longest request)

The difference between between external and internal latencies reflects the time clients spend in the socket queue, as the concurrency in this benchmark is set to saturate the node. Client node network throughput is also a limit in this benchmark, as all three test clients were run concurrently on the same node with GBit networking.

  • Bottlenecks are primarily CPU, memory and network.

Some more notes:

  • Really small pages (redirects) with timeuuid instead of mw revision yield about 2400k req/s on a single node. This compares to about 4400 req/s with the Parsoid Varnishes, which are running on boxes with a cpu performance rating that's double that of the restbase test boxes. The Parsoid front-end caches are set to not cache at all, which probably explains why Varnish doesn't perform better.
  • Latency distribution of 5k requests at -c 10:
Percentage of the requests served within a certain time (ms)
  50%      7
  66%      8
  75%      8
  80%      9
  90%     10
  95%     12
  98%     14
  99%     19
 100%     43 (longest request)
  • Lookup by mediawiki oldid currently involves a second cassandra request to resolve the oldid to a time range, which drops the performance to about 1700 req/s.
  • The enwiki dump through restbase is pretty slow, as none of the v2 API requests are cached in the Parsoid caches & we don't want to hit the Parsoid cluster with excessive concurrency. Right now it's still in the Bs.
GWicke triaged this task as High priority.

Things are now looking pretty solid at moderate load:

pasted_file (657×1 px, 188 KB)

This is doing all random reads (nothing in page cache) from SSD, with a few 'cache misses' every now and then, which result in writes to Cassandra. Load is selected to be moderate. Total dataset is around 200G, close to the SSD space available on the test boxes. English wikipedia alone is around 100G on disk, of which close to 60G are HTML, and around 40G are parsoid metadata.

The request rates are currently double-counted (going to be fixed soon), so divide by two for actual request rates.

Updated graph with a few more writes:

pasted_file (657×1 px, 216 KB)

GWicke lowered the priority of this task from High to Medium.Dec 17 2014, 11:05 PM
GWicke updated the task description. (Show Details)

Closing this task, as there is really not much left for the evaluation stage. We'll do another round of benchmarks with the prod hardware, and will leave a note here so that you can follow.

Some more testing with four independent enwiki dumpers over a few days (stopped eventually, took the screenshot a bit later):

pasted_file (657×1 px, 100 KB)

Disk usage after tracking enwiki updates for ~2 weeks:

  • 98G for the html
  • 60G of data-parsoid
  • 1.3G for the revision table

Latest run:

pasted_file (658×1 px, 103 KB)

GBit network close to saturation on the host running the clients:

pasted_file (387×747 px, 44 KB)