ArangoDB PHPCPP Driver 20180411-2226

results.log

                Name                           Command           State           Ports         
-----------------------------------------------------------------------------------------------
eventstorebenchmarksphpcpp_database_1   /entrypoint.sh arangod   Up      0.0.0.0:8529->8529/tcp


Docker Info
Containers: 26
 Running: 1
 Paused: 0
 Stopped: 25
Images: 251
Server Version: 17.12.0-ce
Storage Driver: overlay2
 Backing Filesystem: extfs
 Supports d_type: true
 Native Overlay Diff: true
Logging Driver: json-file
Cgroup Driver: cgroupfs
Plugins:
 Volume: local
 Network: bridge host macvlan null overlay
 Log: awslogs fluentd gcplogs gelf journald json-file logentries splunk syslog
Swarm: inactive
Runtimes: runc
Default Runtime: runc
Init Binary: docker-init
containerd version: 89623f28b87a6004d4b785663257362d1658a729
runc version: b2567b37d7b75eb4cf325b77297b140ea686ce8f
init version: 949e6fa
Security Options:
 apparmor
 seccomp
  Profile: default
Kernel Version: 4.10.0-42-generic
Operating System: Ubuntu 17.04
OSType: linux
Architecture: x86_64
CPUs: 8
Total Memory: 19.54GiB
Name: ThinkPad
ID: FBQX:4GXR:3KYA:ECVZ:RNJ4:3AGM:EIBS:TSG2:B66W:RRXH:JZ4O:263B
Docker Root Dir: /home/docker
Debug Mode (client): false
Debug Mode (server): false
Registry: https://index.docker.io/v1/
Labels:
Experimental: false
Insecure Registries:
 127.0.0.0/8
Live Restore Enabled: false



Hardware Info
Architecture:          x86_64
CPU op-mode(s):        32-bit, 64-bit
Byte Order:            Little Endian
CPU(s):                8
On-line CPU(s) list:   0-7
Thread(s) pro Kern:    2
Kern(e) pro Socket:    4
Socket(s):             1
NUMA-Knoten:           1
Anbieterkennung:       GenuineIntel
Prozessorfamilie:      6
Modell:                30
Model name:            Intel(R) Core(TM) i7 CPU       Q 720  @ 1.60GHz
Stepping:              5
CPU MHz:               1199.000
CPU max MHz:           1600,0000
CPU min MHz:           933,0000
BogoMIPS:              3192.08
Virtualisierung:       VT-x
L1d Cache:             32K
L1i Cache:             32K
L2 Cache:              256K
L3 Cache:              6144K
NUMA node0 CPU(s):     0-7
Flags:                 fpu vme de pse tsc msr pae mce cx8 apic sep mtrr pge mca cmov pat pse36 clflush dts acpi mmx fxsr sse sse2 ss ht tm pbe syscall nx rdtscp lm constant_tsc arch_perfmon pebs bts rep_good nopl xtopology nonstop_tsc aperfmperf pni dtes64 monitor ds_cpl vmx smx est tm2 ssse3 cx16 xtpr pdcm sse4_1 sse4_2 popcnt lahf_lm tpr_shadow vnmi flexpriority ept vpid dtherm ida


Using 4 CPUs for each service and 13681 MB memory for database.
Waiting for arangodbext database, lean back to enjoy the timer.
19 18 17 16 15 14 13 12 11 10 9 8 7 6 5 4 3 2 1 0 
Starting benchmark arangodbext!
2018-04-11T20:21:42.777186: arangodbext: set up event store tables on database _system

2018-04-11T20:21:43.846549: test 1 create 10 streams with 100 events in each stream, using 1 event per commit

2018-04-11T20:21:44.405646: test 1 using arangodbext took 0.55904412269592 seconds
2018-04-11T20:21:44.405695: test 1 using arangodbext writes 1788.7675755853 events per second

2018-04-11T20:21:44.405712: test 2 create 10 streams with 100 events in each stream, using 5 events per commit

2018-04-11T20:21:44.797303: test 2 using arangodbext took 0.39155292510986 seconds
2018-04-11T20:21:44.797354: test 2 using arangodbext writes 2553.9331617033 events per second

2018-04-11T20:21:44.797377: test 3 create one stream with 2500 events using a single commit

2018-04-11T20:21:45.110132: test 3 using arangodbext took 0.31270599365234 seconds
2018-04-11T20:21:45.110187: test 3 using arangodbext writes 7994.730036353 events per second

2018-04-11T20:21:45.110209: test 4 load one stream with 2500 events

2018-04-11T20:21:45.133194: test 4 using arangodbext took 0.022945880889893 seconds
2018-04-11T20:21:45.133244: test 4 using arangodbext loads 108952.01679101 events per second

2018-04-11T20:21:45.133260: test 5 project 1 stream with 2500 events

2018-04-11T20:21:45.403374: test 5 using arangodbext took 0.26520800590515 seconds
2018-04-11T20:21:45.403419: test 5 using arangodbext loads 9426.563091365 events per second

2018-04-11T20:21:45.403437: test 6 project 10 stream with 100 events

2018-04-11T20:21:45.685337: test 6 using arangodbext took 0.28175210952759 seconds
2018-04-11T20:21:45.685370: test 6 using arangodbext loads 3549.2192114433 events per second

2018-04-11T20:21:45.719931: arangodbext: destroying event-store tables on database _system

Starting real world benchmark arangodbext!
2018-04-11T20:21:45.840927: arangodbext: set up event store tables on database _system

Waiting ... stay patient!
2018-04-11T20:21:47.447791: Writer writer0-user started
2018-04-11T20:22:07.026498: Writer writer0-user wrote 250 events
2018-04-11T20:22:07.026547: Writer writer0-user used 19.406486988068 seconds, avg 12.882290347229 events/second
2018-04-11T20:21:47.249078: Writer writer0-post started
2018-04-11T20:22:06.158397: Writer writer0-post wrote 250 events
2018-04-11T20:22:06.158470: Writer writer0-post used 18.84493803978 seconds, avg 13.26616195141 events/second
2018-04-11T20:21:47.389125: Writer writer0-todo started
2018-04-11T20:22:06.475120: Writer writer0-todo wrote 250 events
2018-04-11T20:22:06.475196: Writer writer0-todo used 18.885054111481 seconds, avg 13.237981661277 events/second
2018-04-11T20:21:47.149774: Writer writer0-blog started
2018-04-11T20:22:08.711796: Writer writer0-blog wrote 250 events
2018-04-11T20:22:08.711857: Writer writer0-blog used 21.454505205154 seconds, avg 11.652564233452 events/second
2018-04-11T20:21:47.144571: Writer writer0-comment started
2018-04-11T20:22:07.812743: Writer writer0-comment wrote 250 events
2018-04-11T20:22:07.812821: Writer writer0-comment used 20.608352899551 seconds, avg 12.131003444018 events/second
2018-04-11T20:21:46.945650: Writer writer1-user started
2018-04-11T20:22:08.044460: Writer writer1-user wrote 250 events
2018-04-11T20:22:08.044531: Writer writer1-user used 21.034569978714 seconds, avg 11.885196619327 events/second
2018-04-11T20:21:47.458267: Writer writer1-post started
2018-04-11T20:22:05.366299: Writer writer1-post wrote 250 events
2018-04-11T20:22:05.366372: Writer writer1-post used 17.739403963089 seconds, avg 14.092919949294 events/second
2018-04-11T20:21:47.464957: Writer writer1-todo started
2018-04-11T20:22:04.589973: Writer writer1-todo wrote 250 events
2018-04-11T20:22:04.590039: Writer writer1-todo used 16.94960308075 seconds, avg 14.7496079294 events/second
2018-04-11T20:21:47.566583: Writer writer1-blog started
2018-04-11T20:22:05.602785: Writer writer1-blog wrote 250 events
2018-04-11T20:22:05.602881: Writer writer1-blog used 18.011467933655 seconds, avg 13.880045808641 events/second
2018-04-11T20:21:47.490358: Writer writer1-comment started
2018-04-11T20:22:06.080263: Writer writer1-comment wrote 250 events
2018-04-11T20:22:06.082144: Writer writer1-comment used 18.432856082916 seconds, avg 13.562738127799 events/second
2018-04-11T20:21:47.398328: Writer writer2-user started
2018-04-11T20:22:05.529404: Writer writer2-user wrote 250 events
2018-04-11T20:22:05.529486: Writer writer2-user used 18.120023012161 seconds, avg 13.796891970403 events/second
2018-04-11T20:21:47.424871: Writer writer2-post started
2018-04-11T20:22:05.394795: Writer writer2-post wrote 250 events
2018-04-11T20:22:05.395054: Writer writer2-post used 17.795668125153 seconds, avg 14.048362682525 events/second
2018-04-11T20:21:47.363319: Writer writer2-todo started
2018-04-11T20:22:05.975301: Writer writer2-todo wrote 250 events
2018-04-11T20:22:05.981536: Writer writer2-todo used 18.393284082413 seconds, avg 13.591917510753 events/second
2018-04-11T20:21:47.394052: Writer writer2-blog started
2018-04-11T20:22:06.949163: Writer writer2-blog wrote 250 events
2018-04-11T20:22:06.949239: Writer writer2-blog used 19.351335048676 seconds, avg 12.919005297111 events/second
2018-04-11T20:21:47.127133: Writer writer2-comment started
2018-04-11T20:22:08.182831: Writer writer2-comment wrote 250 events
2018-04-11T20:22:08.183905: Writer writer2-comment used 20.983902931213 seconds, avg 11.913894227376 events/second
2018-04-11T20:21:47.182573: Writer writer3-user started
2018-04-11T20:22:08.618972: Writer writer3-user wrote 250 events
2018-04-11T20:22:08.619038: Writer writer3-user used 21.292322874069 seconds, avg 11.741321108016 events/second
2018-04-11T20:21:47.205055: Writer writer3-post started
2018-04-11T20:22:06.469788: Writer writer3-post wrote 250 events
2018-04-11T20:22:06.469857: Writer writer3-post used 19.152680873871 seconds, avg 13.053002952765 events/second
2018-04-11T20:21:47.249699: Writer writer3-todo started
2018-04-11T20:22:08.756299: Writer writer3-todo wrote 250 events
2018-04-11T20:22:08.756371: Writer writer3-todo used 21.371182918549 seconds, avg 11.697995424625 events/second
2018-04-11T20:21:47.197272: Writer writer3-blog started
2018-04-11T20:22:05.370065: Writer writer3-blog wrote 250 events
2018-04-11T20:22:05.370137: Writer writer3-blog used 18.025094985962 seconds, avg 13.869552432023 events/second
2018-04-11T20:21:47.244679: Writer writer3-comment started
2018-04-11T20:22:06.376534: Writer writer3-comment wrote 250 events
2018-04-11T20:22:06.376609: Writer writer3-comment used 19.080310106277 seconds, avg 13.102512412403 events/second
2018-04-11T20:21:47.377811: Writer writer4-user started
2018-04-11T20:22:06.765526: Writer writer4-user wrote 250 events
2018-04-11T20:22:06.770319: Writer writer4-user used 19.284246921539 seconds, avg 12.963949332176 events/second
2018-04-11T20:21:47.553410: Writer writer4-post started
2018-04-11T20:22:05.601004: Writer writer4-post wrote 250 events
2018-04-11T20:22:05.601087: Writer writer4-post used 17.949829101562 seconds, avg 13.927709204665 events/second
2018-04-11T20:21:47.279459: Writer writer4-todo started
2018-04-11T20:22:05.203764: Writer writer4-todo wrote 250 events
2018-04-11T20:22:05.203905: Writer writer4-todo used 17.870481014252 seconds, avg 13.989550689801 events/second
2018-04-11T20:21:47.415859: Writer writer4-blog started
2018-04-11T20:22:08.358618: Writer writer4-blog wrote 250 events
2018-04-11T20:22:08.358694: Writer writer4-blog used 20.934724092484 seconds, avg 11.94188177 events/second
2018-04-11T20:21:47.151758: Writer writer4-comment started
2018-04-11T20:22:04.424528: Writer writer4-comment wrote 250 events
2018-04-11T20:22:04.424663: Writer writer4-comment used 17.23012804985 seconds, avg 14.509468488957 events/second
2018-04-11T20:21:47.305425: Writer writer5-user started
2018-04-11T20:22:05.418741: Writer writer5-user wrote 250 events
2018-04-11T20:22:05.418816: Writer writer5-user used 18.008175849915 seconds, avg 13.88258322684 events/second
2018-04-11T20:21:47.300928: Writer writer5-post started
2018-04-11T20:22:08.058475: Writer writer5-post wrote 250 events
2018-04-11T20:22:08.058544: Writer writer5-post used 20.637991905212 seconds, avg 12.113581648264 events/second
2018-04-11T20:21:47.178330: Writer writer5-todo started
2018-04-11T20:22:08.082009: Writer writer5-todo wrote 250 events
2018-04-11T20:22:08.082065: Writer writer5-todo used 20.74054312706 seconds, avg 12.053686273713 events/second
2018-04-11T20:21:47.536030: Writer writer5-blog started
2018-04-11T20:22:06.714076: Writer writer5-blog wrote 250 events
2018-04-11T20:22:06.714157: Writer writer5-blog used 19.006906986237 seconds, avg 13.153113243572 events/second
2018-04-11T20:21:47.213238: Writer writer5-comment started
2018-04-11T20:22:06.228761: Writer writer5-comment wrote 250 events
2018-04-11T20:22:06.228833: Writer writer5-comment used 18.934747934341 seconds, avg 13.203238874207 events/second
2018-04-11T20:21:47.426538: Writer writer6-user started
2018-04-11T20:22:08.359879: Writer writer6-user wrote 250 events
2018-04-11T20:22:08.359941: Writer writer6-user used 20.856557130814 seconds, avg 11.986637987851 events/second
2018-04-11T20:21:47.472475: Writer writer6-post started
2018-04-11T20:22:04.561612: Writer writer6-post wrote 250 events
2018-04-11T20:22:04.561678: Writer writer6-post used 16.942822933197 seconds, avg 14.755510400227 events/second
2018-04-11T20:21:47.444416: Writer writer6-todo started
2018-04-11T20:22:05.096375: Writer writer6-todo wrote 250 events
2018-04-11T20:22:05.096492: Writer writer6-todo used 17.56954908371 seconds, avg 14.229164266475 events/second
2018-04-11T20:21:47.484133: Writer writer6-blog started
2018-04-11T20:22:05.669155: Writer writer6-blog wrote 250 events
2018-04-11T20:22:05.671762: Writer writer6-blog used 18.016047000885 seconds, avg 13.876517972434 events/second
2018-04-11T20:21:47.331478: Writer writer6-comment started
2018-04-11T20:22:08.334086: Writer writer6-comment wrote 250 events
2018-04-11T20:22:08.334140: Writer writer6-comment used 20.995506048203 seconds, avg 11.907310041779 events/second
2018-04-11T20:21:47.439935: Writer writer7-user started
2018-04-11T20:22:07.383358: Writer writer7-user wrote 250 events
2018-04-11T20:22:07.383433: Writer writer7-user used 19.876042842865 seconds, avg 12.577956385808 events/second
2018-04-11T20:21:47.252504: Writer writer7-post started
2018-04-11T20:22:08.617081: Writer writer7-post wrote 250 events
2018-04-11T20:22:08.617279: Writer writer7-post used 21.323987960815 seconds, avg 11.723885816265 events/second
2018-04-11T20:21:47.167223: Writer writer7-todo started
2018-04-11T20:22:06.662109: Writer writer7-todo wrote 250 events
2018-04-11T20:22:06.662191: Writer writer7-todo used 19.35129404068 seconds, avg 12.919032674221 events/second
2018-04-11T20:21:47.471471: Writer writer7-blog started
2018-04-11T20:22:06.568759: Writer writer7-blog wrote 250 events
2018-04-11T20:22:06.568829: Writer writer7-blog used 18.905610084534 seconds, avg 13.223588071592 events/second
2018-04-11T20:21:47.294470: Writer writer7-comment started
2018-04-11T20:22:08.388324: Writer writer7-comment wrote 250 events
2018-04-11T20:22:08.388388: Writer writer7-comment used 21.081543922424 seconds, avg 11.858713997416 events/second
2018-04-11T20:21:47.428627: Writer writer8-user started
2018-04-11T20:22:04.850950: Writer writer8-user wrote 250 events
2018-04-11T20:22:04.851012: Writer writer8-user used 17.246142864227 seconds, avg 14.495994957722 events/second
2018-04-11T20:21:47.322621: Writer writer8-post started
2018-04-11T20:22:05.474760: Writer writer8-post wrote 250 events
2018-04-11T20:22:05.480293: Writer writer8-post used 18.007009029388 seconds, avg 13.883482792283 events/second
2018-04-11T20:21:47.482936: Writer writer8-todo started
2018-04-11T20:22:05.515601: Writer writer8-todo wrote 250 events
2018-04-11T20:22:05.520126: Writer writer8-todo used 17.940385818481 seconds, avg 13.935040334666 events/second
2018-04-11T20:21:47.619915: Writer writer8-blog started
2018-04-11T20:22:02.857658: Writer writer8-blog wrote 250 events
2018-04-11T20:22:02.857726: Writer writer8-blog used 15.165429830551 seconds, avg 16.484860817883 events/second
2018-04-11T20:21:47.460712: Writer writer8-comment started
2018-04-11T20:22:04.052799: Writer writer8-comment wrote 250 events
2018-04-11T20:22:04.052874: Writer writer8-comment used 16.413300991058 seconds, avg 15.231549103754 events/second
2018-04-11T20:21:47.289182: Writer writer9-user started
2018-04-11T20:22:08.234748: Writer writer9-user wrote 250 events
2018-04-11T20:22:08.238690: Writer writer9-user used 20.865529060364 seconds, avg 11.981483875954 events/second
2018-04-11T20:21:47.222816: Writer writer9-post started
2018-04-11T20:22:05.505300: Writer writer9-post wrote 250 events
2018-04-11T20:22:05.505364: Writer writer9-post used 18.166322946548 seconds, avg 13.761728266947 events/second
2018-04-11T20:21:47.280062: Writer writer9-todo started
2018-04-11T20:22:04.883992: Writer writer9-todo wrote 250 events
2018-04-11T20:22:04.884053: Writer writer9-todo used 17.531038999557 seconds, avg 14.260421188174 events/second
2018-04-11T20:21:47.495152: Writer writer9-blog started
2018-04-11T20:22:06.503813: Writer writer9-blog wrote 250 events
2018-04-11T20:22:06.508945: Writer writer9-blog used 18.834299087524 seconds, avg 13.273655623617 events/second
2018-04-11T20:21:47.549972: Writer writer9-comment started
2018-04-11T20:22:05.593897: Writer writer9-comment wrote 250 events
2018-04-11T20:22:05.594095: Writer writer9-comment used 18.01690196991 seconds, avg 13.875859480033 events/second
2018-04-11T20:21:47.243394: Projection projectorsuser started
2018-04-11T20:22:10.822079: Projection projectorsuser read 2500 events
2018-04-11T20:22:10.822159: projection projectorsuser used 23.473509073257 seconds, avg 106.50303677213 events/second
2018-04-11T20:21:47.371108: Projection projectorspost started
2018-04-11T20:22:10.683604: Projection projectorspost read 2500 events
2018-04-11T20:22:10.683740: projection projectorspost used 22.985666036606 seconds, avg 108.76343526521 events/second
2018-04-11T20:21:47.446641: Projection projectorstodo started
2018-04-11T20:22:10.975618: Projection projectorstodo read 2500 events
2018-04-11T20:22:10.975683: projection projectorstodo used 23.366949081421 seconds, avg 106.98872117575 events/second
2018-04-11T20:21:47.450901: Projection projectorsblog started
2018-04-11T20:22:11.182159: Projection projectorsblog read 2500 events
2018-04-11T20:22:11.182242: projection projectorsblog used 23.496394872665 seconds, avg 106.39930140553 events/second
2018-04-11T20:21:47.428720: Projection projectorscomment started
2018-04-11T20:22:10.272520: Projection projectorscomment read 2500 events
2018-04-11T20:22:10.272594: projection projectorscomment used 22.774895906448 seconds, avg 109.76998578914 events/second
2018-04-11T20:21:47.232608: Projection projectorsall started
2018-04-11T20:21:58.818656: Projection projectorsall read 2500 events
2018-04-11T20:21:58.818729: projection projectorsall used 11.492414951324 seconds, avg 217.53478364544 events/second

arangodbext real world test duration 25 seconds
arangodbext avg writes 500 events/second
arangodbext avg reads 1000 events/second

Dumping logs arangodbext!
Attaching to eventstorebenchmarksphpcpp_database_1
database_1  | automaticaly choosing storage engine
database_1  | Initializing database...Hang on...
database_1  | Database initialized...Starting System...
database_1  | 2018-04-11T20:21:21Z [1] INFO ArangoDB 3.3.5 [linux] 64bit, using jemalloc, build tags/v3.3.5-0-g3f737e7648, VPack 0.1.30, RocksDB 5.6.0, ICU 58.1, V8 5.7.492.77, OpenSSL 1.1.0f  25 May 2017
database_1  | 2018-04-11T20:21:21Z [1] INFO {authentication} Jwt secret not specified, generating...
database_1  | 2018-04-11T20:21:21Z [1] INFO detected operating system: Linux version 4.10.0-42-generic (buildd@lgw01-amd64-012) (gcc version 6.3.0 20170406 (Ubuntu 6.3.0-12ubuntu2) ) #46-Ubuntu SMP Mon Dec 4 14:38:01 UTC 2017
database_1  | 2018-04-11T20:21:21Z [1] WARNING {memory} /sys/kernel/mm/transparent_hugepage/enabled is set to 'always'. It is recommended to set it to a value of 'never' or 'madvise'
database_1  | 2018-04-11T20:21:21Z [1] WARNING {memory} execute 'sudo bash -c "echo madvise > /sys/kernel/mm/transparent_hugepage/enabled"'
database_1  | 2018-04-11T20:21:21Z [1] WARNING {memory} execute 'sudo bash -c "echo madvise > /sys/kernel/mm/transparent_hugepage/defrag"'
database_1  | 2018-04-11T20:21:21Z [1] INFO using storage engine mmfiles
database_1  | 2018-04-11T20:21:21Z [1] INFO {cluster} Starting up with role SINGLE
database_1  | 2018-04-11T20:21:21Z [1] INFO {syscall} file-descriptors (nofiles) hard limit is 1048576, soft limit is 1048576
database_1  | 2018-04-11T20:21:21Z [1] INFO {authentication} Authentication is turned off, authentication for unix sockets is turned on
database_1  | 2018-04-11T20:21:22Z [1] INFO using endpoint 'http+tcp://0.0.0.0:8529' for non-encrypted requests
database_1  | 2018-04-11T20:21:22Z [1] INFO ArangoDB (version 3.3.5 [linux]) is ready for business. Have fun!

2018-04-16T20:27:51.009393: arangodbext: set up event store tables on database _system

2018-04-16T20:27:52.095605: test 1 create 10 streams with 100 events in each stream, using 1 event per commit

2018-04-16T20:27:52.723915: test 1 using arangodbext took 0.62823891639709 seconds
2018-04-16T20:27:52.723967: test 1 using arangodbext writes 1591.7511219059 events per second

2018-04-16T20:27:52.723984: test 2 create 10 streams with 100 events in each stream, using 5 events per commit

2018-04-16T20:27:53.235377: test 2 using arangodbext took 0.51135611534119 seconds
2018-04-16T20:27:53.235413: test 2 using arangodbext writes 1955.5843178541 events per second

2018-04-16T20:27:53.235427: test 3 create one stream with 2500 events using a single commit

2018-04-16T20:27:53.714208: test 3 using arangodbext took 0.47873997688293 seconds
2018-04-16T20:27:53.714275: test 3 using arangodbext writes 5222.0414436192 events per second

2018-04-16T20:27:53.714310: test 4 load one stream with 2500 events

2018-04-16T20:27:53.738830: test 4 using arangodbext took 0.024451017379761 seconds
2018-04-16T20:27:53.738885: test 4 using arangodbext loads 102245.23426454 events per second

2018-04-16T20:27:53.738910: test 5 project 1 stream with 2500 events

2018-04-16T20:27:54.557622: test 5 using arangodbext took 0.81381106376648 seconds
2018-04-16T20:27:54.557659: test 5 using arangodbext loads 3071.9661003741 events per second

2018-04-16T20:27:54.557676: test 6 project 10 stream with 100 events

2018-04-16T20:27:55.036936: test 6 using arangodbext took 0.47910618782043 seconds
2018-04-16T20:27:55.036998: test 6 using arangodbext loads 2087.2199638022 events per second

2018-04-16T20:27:55.079474: arangodbext: destroying event-store tables on database _system

Starting real world benchmark arangodbext!
2018-04-16T20:27:55.313914: arangodbext: set up event store tables on database _system

Waiting ... stay patient!
2018-04-16T20:27:56.424968: Writer writer0-user started
2018-04-16T20:28:18.165618: Writer writer0-user wrote 250 events
2018-04-16T20:28:18.165696: Writer writer0-user used 21.716083049774 seconds, avg 11.512205006169 events/second
2018-04-16T20:27:56.748008: Writer writer0-post started
2018-04-16T20:28:16.095478: Writer writer0-post wrote 250 events
2018-04-16T20:28:16.095551: Writer writer0-post used 19.066025018692 seconds, avg 13.112329379349 events/second
2018-04-16T20:27:56.701270: Writer writer0-todo started
2018-04-16T20:28:18.916807: Writer writer0-todo wrote 250 events
2018-04-16T20:28:18.916891: Writer writer0-todo used 22.195496082306 seconds, avg 11.263546400267 events/second
2018-04-16T20:27:56.878117: Writer writer0-blog started
2018-04-16T20:28:18.602814: Writer writer0-blog wrote 250 events
2018-04-16T20:28:18.602876: Writer writer0-blog used 21.70997595787 seconds, avg 11.515443429562 events/second
2018-04-16T20:27:56.866364: Writer writer0-comment started
2018-04-16T20:28:15.627134: Writer writer0-comment wrote 250 events
2018-04-16T20:28:15.627212: Writer writer0-comment used 18.68479013443 seconds, avg 13.37986662956 events/second
2018-04-16T20:27:56.715300: Writer writer1-user started
2018-04-16T20:28:17.237649: Writer writer1-user wrote 250 events
2018-04-16T20:28:17.237736: Writer writer1-user used 20.441823005676 seconds, avg 12.229829009408 events/second
2018-04-16T20:27:56.896748: Writer writer1-post started
2018-04-16T20:28:16.899348: Writer writer1-post wrote 250 events
2018-04-16T20:28:16.899413: Writer writer1-post used 19.977267026901 seconds, avg 12.514224276191 events/second
2018-04-16T20:27:56.988331: Writer writer1-todo started
2018-04-16T20:28:15.449139: Writer writer1-todo wrote 250 events
2018-04-16T20:28:15.451372: Writer writer1-todo used 18.360484838486 seconds, avg 13.616198166835 events/second
2018-04-16T20:27:56.861586: Writer writer1-blog started
2018-04-16T20:28:17.548783: Writer writer1-blog wrote 250 events
2018-04-16T20:28:17.553236: Writer writer1-blog used 20.61751294136 seconds, avg 12.125613826995 events/second
2018-04-16T20:27:56.665182: Writer writer1-comment started
2018-04-16T20:28:15.603649: Writer writer1-comment wrote 250 events
2018-04-16T20:28:15.603727: Writer writer1-comment used 18.854348182678 seconds, avg 13.2595408538 events/second
2018-04-16T20:27:57.016871: Writer writer2-user started
2018-04-16T20:28:16.608941: Writer writer2-user wrote 250 events
2018-04-16T20:28:16.609011: Writer writer2-user used 19.448359012604 seconds, avg 12.854554969804 events/second
2018-04-16T20:27:56.765801: Writer writer2-post started
2018-04-16T20:28:18.493830: Writer writer2-post wrote 250 events
2018-04-16T20:28:18.493907: Writer writer2-post used 21.679524898529 seconds, avg 11.531618020696 events/second
2018-04-16T20:27:56.863203: Writer writer2-todo started
2018-04-16T20:28:17.605280: Writer writer2-todo wrote 250 events
2018-04-16T20:28:17.605360: Writer writer2-todo used 20.529438972473 seconds, avg 12.177634290699 events/second
2018-04-16T20:27:56.983275: Writer writer2-blog started
2018-04-16T20:28:14.994142: Writer writer2-blog wrote 250 events
2018-04-16T20:28:15.003273: Writer writer2-blog used 17.869014978409 seconds, avg 13.990698440965 events/second
2018-04-16T20:27:57.012391: Writer writer2-comment started
2018-04-16T20:28:16.161459: Writer writer2-comment wrote 250 events
2018-04-16T20:28:16.161551: Writer writer2-comment used 18.983705043793 seconds, avg 13.16918901886 events/second
2018-04-16T20:27:56.975571: Writer writer3-user started
2018-04-16T20:28:16.604112: Writer writer3-user wrote 250 events
2018-04-16T20:28:16.604259: Writer writer3-user used 19.487954139709 seconds, avg 12.828437413581 events/second
2018-04-16T20:27:57.078121: Writer writer3-post started
2018-04-16T20:28:15.724408: Writer writer3-post wrote 250 events
2018-04-16T20:28:15.724499: Writer writer3-post used 18.573431968689 seconds, avg 13.460086451521 events/second
2018-04-16T20:27:56.889406: Writer writer3-todo started
2018-04-16T20:28:18.335551: Writer writer3-todo wrote 250 events
2018-04-16T20:28:18.335645: Writer writer3-todo used 21.410305976868 seconds, avg 11.676619674194 events/second
2018-04-16T20:27:56.873100: Writer writer3-blog started
2018-04-16T20:28:16.264402: Writer writer3-blog wrote 250 events
2018-04-16T20:28:16.264474: Writer writer3-blog used 19.361012935638 seconds, avg 12.912547542377 events/second
2018-04-16T20:27:57.011197: Writer writer3-comment started
2018-04-16T20:28:15.207701: Writer writer3-comment wrote 250 events
2018-04-16T20:28:15.210253: Writer writer3-comment used 18.124871969223 seconds, avg 13.793200880233 events/second
2018-04-16T20:27:56.871863: Writer writer4-user started
2018-04-16T20:28:16.391290: Writer writer4-user wrote 250 events
2018-04-16T20:28:16.391375: Writer writer4-user used 19.434126853943 seconds, avg 12.863968722592 events/second
2018-04-16T20:27:56.998797: Writer writer4-post started
2018-04-16T20:28:15.174187: Writer writer4-post wrote 250 events
2018-04-16T20:28:15.174348: Writer writer4-post used 18.047830104828 seconds, avg 13.852080751421 events/second
2018-04-16T20:27:57.050652: Writer writer4-todo started
2018-04-16T20:28:15.206955: Writer writer4-todo wrote 250 events
2018-04-16T20:28:15.209928: Writer writer4-todo used 18.049562931061 seconds, avg 13.850750899335 events/second
2018-04-16T20:27:56.511199: Writer writer4-blog started
2018-04-16T20:28:18.086473: Writer writer4-blog wrote 250 events
2018-04-16T20:28:18.086555: Writer writer4-blog used 21.540832996368 seconds, avg 11.605865011912 events/second
2018-04-16T20:27:57.061164: Writer writer4-comment started
2018-04-16T20:28:15.072486: Writer writer4-comment wrote 250 events
2018-04-16T20:28:15.077563: Writer writer4-comment used 17.918250799179 seconds, avg 13.952254759792 events/second
2018-04-16T20:27:56.661226: Writer writer5-user started
2018-04-16T20:28:17.561492: Writer writer5-user wrote 250 events
2018-04-16T20:28:17.561646: Writer writer5-user used 20.890650033951 seconds, avg 11.967076160565 events/second
2018-04-16T20:27:56.879385: Writer writer5-post started
2018-04-16T20:28:14.009920: Writer writer5-post wrote 250 events
2018-04-16T20:28:14.009984: Writer writer5-post used 17.069821834564 seconds, avg 14.645729898234 events/second
2018-04-16T20:27:56.998622: Writer writer5-todo started
2018-04-16T20:28:16.853103: Writer writer5-todo wrote 250 events
2018-04-16T20:28:16.853189: Writer writer5-todo used 19.776770830154 seconds, avg 12.64109303521 events/second
2018-04-16T20:27:56.896369: Writer writer5-blog started
2018-04-16T20:28:18.594106: Writer writer5-blog wrote 250 events
2018-04-16T20:28:18.594239: Writer writer5-blog used 21.640590906143 seconds, avg 11.552364770642 events/second
2018-04-16T20:27:57.012934: Writer writer5-comment started
2018-04-16T20:28:16.944944: Writer writer5-comment wrote 250 events
2018-04-16T20:28:16.947553: Writer writer5-comment used 19.901105165482 seconds, avg 12.562116421234 events/second
2018-04-16T20:27:57.059088: Writer writer6-user started
2018-04-16T20:28:14.837778: Writer writer6-user wrote 250 events
2018-04-16T20:28:14.837916: Writer writer6-user used 17.659226894379 seconds, avg 14.156905140597 events/second
2018-04-16T20:27:57.072637: Writer writer6-post started
2018-04-16T20:28:15.924023: Writer writer6-post wrote 250 events
2018-04-16T20:28:15.924104: Writer writer6-post used 18.74248790741 seconds, avg 13.338677406918 events/second
2018-04-16T20:27:57.021816: Writer writer6-todo started
2018-04-16T20:28:16.715976: Writer writer6-todo wrote 250 events
2018-04-16T20:28:16.716055: Writer writer6-todo used 19.614182949066 seconds, avg 12.745878869856 events/second
2018-04-16T20:27:56.699057: Writer writer6-blog started
2018-04-16T20:28:18.219851: Writer writer6-blog wrote 250 events
2018-04-16T20:28:18.219926: Writer writer6-blog used 21.440238952637 seconds, avg 11.660317804865 events/second
2018-04-16T20:27:56.695444: Writer writer6-comment started
2018-04-16T20:28:16.072979: Writer writer6-comment wrote 250 events
2018-04-16T20:28:16.073061: Writer writer6-comment used 19.285645008087 seconds, avg 12.963009528339 events/second
2018-04-16T20:27:56.939058: Writer writer7-user started
2018-04-16T20:28:15.817960: Writer writer7-user wrote 250 events
2018-04-16T20:28:15.818039: Writer writer7-user used 18.78587603569 seconds, avg 13.307870206587 events/second
2018-04-16T20:27:57.064967: Writer writer7-post started
2018-04-16T20:28:16.630652: Writer writer7-post wrote 250 events
2018-04-16T20:28:16.630744: Writer writer7-post used 19.463912963867 seconds, avg 12.844282671429 events/second
2018-04-16T20:27:56.991370: Writer writer7-todo started
2018-04-16T20:28:15.795181: Writer writer7-todo wrote 250 events
2018-04-16T20:28:15.798728: Writer writer7-todo used 18.712125062943 seconds, avg 13.360321137181 events/second
2018-04-16T20:27:56.973742: Writer writer7-blog started
2018-04-16T20:28:15.587304: Writer writer7-blog wrote 250 events
2018-04-16T20:28:15.587387: Writer writer7-blog used 18.539319992065 seconds, avg 13.484852740392 events/second
2018-04-16T20:27:56.769048: Writer writer7-comment started
2018-04-16T20:28:18.238997: Writer writer7-comment wrote 250 events
2018-04-16T20:28:18.239077: Writer writer7-comment used 21.348037958145 seconds, avg 11.710678072156 events/second
2018-04-16T20:27:56.705709: Writer writer8-user started
2018-04-16T20:28:18.674911: Writer writer8-user wrote 250 events
2018-04-16T20:28:18.674994: Writer writer8-user used 21.880444049835 seconds, avg 11.425727897962 events/second
2018-04-16T20:27:56.729169: Writer writer8-post started
2018-04-16T20:28:18.050451: Writer writer8-post wrote 250 events
2018-04-16T20:28:18.050538: Writer writer8-post used 21.235481977463 seconds, avg 11.772749036981 events/second
2018-04-16T20:27:56.765415: Writer writer8-todo started
2018-04-16T20:28:18.450640: Writer writer8-todo wrote 250 events
2018-04-16T20:28:18.450732: Writer writer8-todo used 21.596493959427 seconds, avg 11.575953044493 events/second
2018-04-16T20:27:56.753020: Writer writer8-blog started
2018-04-16T20:28:18.599747: Writer writer8-blog wrote 250 events
2018-04-16T20:28:18.599812: Writer writer8-blog used 21.763026952744 seconds, avg 11.487372622515 events/second
2018-04-16T20:27:57.050954: Writer writer8-comment started
2018-04-16T20:28:16.875738: Writer writer8-comment wrote 250 events
2018-04-16T20:28:16.875819: Writer writer8-comment used 19.778692007065 seconds, avg 12.639865159471 events/second
2018-04-16T20:27:56.995465: Writer writer9-user started
2018-04-16T20:28:15.349232: Writer writer9-user wrote 250 events
2018-04-16T20:28:15.349315: Writer writer9-user used 18.2757999897 seconds, avg 13.6792917487 events/second
2018-04-16T20:27:56.597401: Writer writer9-post started
2018-04-16T20:28:15.535853: Writer writer9-post wrote 250 events
2018-04-16T20:28:15.535936: Writer writer9-post used 18.911247014999 seconds, avg 13.219646478189 events/second
2018-04-16T20:27:57.011623: Writer writer9-todo started
2018-04-16T20:28:14.938478: Writer writer9-todo wrote 250 events
2018-04-16T20:28:14.938568: Writer writer9-todo used 17.847795009613 seconds, avg 14.007332550903 events/second
2018-04-16T20:27:56.669976: Writer writer9-blog started
2018-04-16T20:28:15.778116: Writer writer9-blog wrote 250 events
2018-04-16T20:28:15.778197: Writer writer9-blog used 19.020285129547 seconds, avg 13.143861845248 events/second
2018-04-16T20:27:56.662315: Writer writer9-comment started
2018-04-16T20:28:18.021365: Writer writer9-comment wrote 250 events
2018-04-16T20:28:18.021682: Writer writer9-comment used 21.26015496254 seconds, avg 11.759086443184 events/second
2018-04-16T20:27:56.643760: Projection projectorsuser started
2018-04-16T20:28:20.223862: Projection projectorsuser read 2500 events
2018-04-16T20:28:20.223931: projection projectorsuser used 23.443000078201 seconds, avg 106.64164107241 events/second
2018-04-16T20:27:56.884874: Projection projectorspost started
2018-04-16T20:28:19.655611: Projection projectorspost read 2500 events
2018-04-16T20:28:19.655709: projection projectorspost used 22.707007884979 seconds, avg 110.09816936972 events/second
2018-04-16T20:27:56.532494: Projection projectorstodo started
2018-04-16T20:28:20.067776: Projection projectorstodo read 2500 events
2018-04-16T20:28:20.067850: projection projectorstodo used 23.503004074097 seconds, avg 106.36938121265 events/second
2018-04-16T20:27:56.532034: Projection projectorsblog started
2018-04-16T20:28:20.210179: Projection projectorsblog read 2500 events
2018-04-16T20:28:20.210248: projection projectorsblog used 23.65916800499 seconds, avg 105.66728295233 events/second
2018-04-16T20:27:56.992544: Projection projectorscomment started
2018-04-16T20:28:20.094051: Projection projectorscomment read 2500 events
2018-04-16T20:28:20.094114: projection projectorscomment used 22.922054052353 seconds, avg 109.06526938162 events/second
2018-04-16T20:27:56.725544: Projection projectorsall started
2018-04-16T20:28:05.732385: Projection projectorsall read 2500 events
2018-04-16T20:28:05.732465: projection projectorsall used 8.9384508132935 seconds, avg 279.69052492653 events/second

arangodbext real world test duration 24 seconds
arangodbext avg writes 520 events/second
arangodbext avg reads 1041 events/second

Dumping logs arangodbext!
Attaching to eventstorebenchmarks_database_1
�[36mdatabase_1  |�[0m automaticaly choosing storage engine
�[36mdatabase_1  |�[0m Initializing database...Hang on...
�[36mdatabase_1  |�[0m Database initialized...Starting System...
�[36mdatabase_1  |�[0m 2018-04-16T20:27:30Z [1] INFO ArangoDB 3.3.5 [linux] 64bit, using jemalloc, build tags/v3.3.5-0-g3f737e7648, VPack 0.1.30, RocksDB 5.6.0, ICU 58.1, V8 5.7.492.77, OpenSSL 1.1.0f  25 May 2017
�[36mdatabase_1  |�[0m 2018-04-16T20:27:30Z [1] INFO {authentication} Jwt secret not specified, generating...
�[36mdatabase_1  |�[0m 2018-04-16T20:27:30Z [1] INFO detected operating system: Linux version 4.10.0-42-generic (buildd@lgw01-amd64-012) (gcc version 6.3.0 20170406 (Ubuntu 6.3.0-12ubuntu2) ) #46-Ubuntu SMP Mon Dec 4 14:38:01 UTC 2017
�[36mdatabase_1  |�[0m 2018-04-16T20:27:30Z [1] WARNING {memory} /sys/kernel/mm/transparent_hugepage/enabled is set to 'always'. It is recommended to set it to a value of 'never' or 'madvise'
�[36mdatabase_1  |�[0m 2018-04-16T20:27:30Z [1] WARNING {memory} execute 'sudo bash -c "echo madvise > /sys/kernel/mm/transparent_hugepage/enabled"'
�[36mdatabase_1  |�[0m 2018-04-16T20:27:30Z [1] WARNING {memory} execute 'sudo bash -c "echo madvise > /sys/kernel/mm/transparent_hugepage/defrag"'
�[36mdatabase_1  |�[0m 2018-04-16T20:27:30Z [1] INFO using storage engine mmfiles
�[36mdatabase_1  |�[0m 2018-04-16T20:27:30Z [1] INFO {cluster} Starting up with role SINGLE
�[36mdatabase_1  |�[0m 2018-04-16T20:27:30Z [1] INFO {syscall} file-descriptors (nofiles) hard limit is 1048576, soft limit is 1048576
�[36mdatabase_1  |�[0m 2018-04-16T20:27:30Z [1] INFO {authentication} Authentication is turned off, authentication for unix sockets is turned on
�[36mdatabase_1  |�[0m 2018-04-16T20:27:30Z [1] INFO using endpoint 'http+tcp://0.0.0.0:8529' for non-encrypted requests
�[36mdatabase_1  |�[0m 2018-04-16T20:27:31Z [1] INFO Please note that a new bugfix version '3.3.7' is available
�[36mdatabase_1  |�[0m 2018-04-16T20:27:31Z [1] INFO ArangoDB (version 3.3.5 [linux]) is ready for business. Have fun!

ArangoDB Polyfill

             Name                        Command           State           Ports         
-----------------------------------------------------------------------------------------
eventstorebenchmarks_database_1   /entrypoint.sh arangod   Up      0.0.0.0:8529->8529/tcp


Docker Info
Containers: 33
 Running: 1
 Paused: 0
 Stopped: 32
Images: 284
Server Version: 17.12.0-ce
Storage Driver: overlay2
 Backing Filesystem: extfs
 Supports d_type: true
 Native Overlay Diff: true
Logging Driver: json-file
Cgroup Driver: cgroupfs
Plugins:
 Volume: local
 Network: bridge host macvlan null overlay
 Log: awslogs fluentd gcplogs gelf journald json-file logentries splunk syslog
Swarm: inactive
Runtimes: runc
Default Runtime: runc
Init Binary: docker-init
containerd version: 89623f28b87a6004d4b785663257362d1658a729
runc version: b2567b37d7b75eb4cf325b77297b140ea686ce8f
init version: 949e6fa
Security Options:
 apparmor
 seccomp
  Profile: default
Kernel Version: 4.10.0-42-generic
Operating System: Ubuntu 17.04
OSType: linux
Architecture: x86_64
CPUs: 8
Total Memory: 19.54GiB
Name: ThinkPad
ID: FBQX:4GXR:3KYA:ECVZ:RNJ4:3AGM:EIBS:TSG2:B66W:RRXH:JZ4O:263B
Docker Root Dir: /home/docker
Debug Mode (client): false
Debug Mode (server): false
Registry: https://index.docker.io/v1/
Labels:
Experimental: false
Insecure Registries:
 127.0.0.0/8
Live Restore Enabled: false



Hardware Info
Architecture:          x86_64
CPU op-mode(s):        32-bit, 64-bit
Byte Order:            Little Endian
CPU(s):                8
On-line CPU(s) list:   0-7
Thread(s) pro Kern:    2
Kern(e) pro Socket:    4
Socket(s):             1
NUMA-Knoten:           1
Anbieterkennung:       GenuineIntel
Prozessorfamilie:      6
Modell:                30
Model name:            Intel(R) Core(TM) i7 CPU       Q 720  @ 1.60GHz
Stepping:              5
CPU MHz:               1600.000
CPU max MHz:           1600,0000
CPU min MHz:           933,0000
BogoMIPS:              3191.77
Virtualisierung:       VT-x
L1d Cache:             32K
L1i Cache:             32K
L2 Cache:              256K
L3 Cache:              6144K
NUMA node0 CPU(s):     0-7
Flags:                 fpu vme de pse tsc msr pae mce cx8 apic sep mtrr pge mca cmov pat pse36 clflush dts acpi mmx fxsr sse sse2 ss ht tm pbe syscall nx rdtscp lm constant_tsc arch_perfmon pebs bts rep_good nopl xtopology nonstop_tsc aperfmperf pni dtes64 monitor ds_cpl vmx smx est tm2 ssse3 cx16 xtpr pdcm sse4_1 sse4_2 popcnt lahf_lm tpr_shadow vnmi flexpriority ept vpid dtherm ida


Using 4 CPUs for each service and 15092 MB memory for database.
Waiting for arangodb database, lean back to enjoy the timer.
19 18 17 16 15 14 13 12 11 10 9 8 7 6 5 4 3 2 1 0 
Starting benchmark arangodb!
2018-04-18T20:11:25.124976: arangodb: set up event store tables on database _system

2018-04-18T20:11:26.210713: test 1 create 10 streams with 100 events in each stream, using 1 event per commit

2018-04-18T20:11:26.721307: test 1 using arangodb took 0.51055097579956 seconds
2018-04-18T20:11:26.721423: test 1 using arangodb writes 1958.6682768237 events per second

2018-04-18T20:11:26.721455: test 2 create 10 streams with 100 events in each stream, using 5 events per commit

2018-04-18T20:11:27.037986: test 2 using arangodb took 0.31647992134094 seconds
2018-04-18T20:11:27.038038: test 2 using arangodb writes 3159.7581159745 events per second

2018-04-18T20:11:27.038055: test 3 create one stream with 2500 events using a single commit

2018-04-18T20:11:27.289575: test 3 using arangodb took 0.25148105621338 seconds
2018-04-18T20:11:27.289630: test 3 using arangodb writes 9941.106648919 events per second

2018-04-18T20:11:27.289651: test 4 load one stream with 2500 events

2018-04-18T20:11:27.324424: test 4 using arangodb took 0.034739017486572 seconds
2018-04-18T20:11:27.324459: test 4 using arangodb loads 71965.190177481 events per second

2018-04-18T20:11:27.324471: test 5 project 1 stream with 2500 events

2018-04-18T20:11:27.555198: test 5 using arangodb took 0.22779703140259 seconds
2018-04-18T20:11:27.555230: test 5 using arangodb loads 10974.682086975 events per second

2018-04-18T20:11:27.555241: test 6 project 10 stream with 100 events

2018-04-18T20:11:27.798818: test 6 using arangodb took 0.24349403381348 seconds
2018-04-18T20:11:27.798870: test 6 using arangodb loads 4106.8768065423 events per second

2018-04-18T20:11:27.840423: arangodb: destroying event-store tables on database _system

Starting real world benchmark arangodb!
2018-04-18T20:11:28.004529: arangodb: set up event store tables on database _system

Waiting ... stay patient!
2018-04-18T20:11:29.743539: Writer writer0-user started
2018-04-18T20:11:48.398477: Writer writer0-user wrote 250 events
2018-04-18T20:11:48.398632: Writer writer0-user used 18.578480958939 seconds, avg 13.456428464337 events/second
2018-04-18T20:11:29.548165: Writer writer0-post started
2018-04-18T20:11:47.084846: Writer writer0-post wrote 250 events
2018-04-18T20:11:47.084913: Writer writer0-post used 17.413964033127 seconds, avg 14.356294725567 events/second
2018-04-18T20:11:29.556418: Writer writer0-todo started
2018-04-18T20:11:48.855782: Writer writer0-todo wrote 250 events
2018-04-18T20:11:48.856252: Writer writer0-todo used 19.20697593689 seconds, avg 13.016104191594 events/second
2018-04-18T20:11:29.746124: Writer writer0-blog started
2018-04-18T20:11:48.146379: Writer writer0-blog wrote 250 events
2018-04-18T20:11:48.146443: Writer writer0-blog used 18.309548854828 seconds, avg 13.654077551675 events/second
2018-04-18T20:11:29.758338: Writer writer0-comment started
2018-04-18T20:11:48.641399: Writer writer0-comment wrote 250 events
2018-04-18T20:11:48.642479: Writer writer0-comment used 18.810269117355 seconds, avg 13.290612613795 events/second
2018-04-18T20:11:29.622865: Writer writer1-user started
2018-04-18T20:11:48.028744: Writer writer1-user wrote 250 events
2018-04-18T20:11:48.028817: Writer writer1-user used 18.309010982513 seconds, avg 13.654478673849 events/second
2018-04-18T20:11:29.631365: Writer writer1-post started
2018-04-18T20:11:46.817430: Writer writer1-post wrote 250 events
2018-04-18T20:11:46.817511: Writer writer1-post used 17.053143978119 seconds, avg 14.660053320419 events/second
2018-04-18T20:11:29.759575: Writer writer1-todo started
2018-04-18T20:11:48.723967: Writer writer1-todo wrote 250 events
2018-04-18T20:11:48.724020: Writer writer1-todo used 18.88723897934 seconds, avg 13.236450297128 events/second
2018-04-18T20:11:29.766741: Writer writer1-blog started
2018-04-18T20:11:48.428322: Writer writer1-blog wrote 250 events
2018-04-18T20:11:48.428386: Writer writer1-blog used 18.599191904068 seconds, avg 13.441444192278 events/second
2018-04-18T20:11:29.435811: Writer writer1-comment started
2018-04-18T20:11:50.205050: Writer writer1-comment wrote 250 events
2018-04-18T20:11:50.205117: Writer writer1-comment used 20.682569026947 seconds, avg 12.087473257035 events/second
2018-04-18T20:11:29.124178: Writer writer2-user started
2018-04-18T20:11:49.616739: Writer writer2-user wrote 250 events
2018-04-18T20:11:49.616809: Writer writer2-user used 20.392729997635 seconds, avg 12.2592708298 events/second
2018-04-18T20:11:29.388526: Writer writer2-post started
2018-04-18T20:11:47.108226: Writer writer2-post wrote 250 events
2018-04-18T20:11:47.108294: Writer writer2-post used 17.647873163223 seconds, avg 14.166012963023 events/second
2018-04-18T20:11:29.427960: Writer writer2-todo started
2018-04-18T20:11:50.425134: Writer writer2-todo wrote 250 events
2018-04-18T20:11:50.425204: Writer writer2-todo used 20.958109855652 seconds, avg 11.928556617074 events/second
2018-04-18T20:11:29.436862: Writer writer2-blog started
2018-04-18T20:11:50.339270: Writer writer2-blog wrote 250 events
2018-04-18T20:11:50.339350: Writer writer2-blog used 20.854279994965 seconds, avg 11.987946841625 events/second
2018-04-18T20:11:29.449103: Writer writer2-comment started
2018-04-18T20:11:47.996171: Writer writer2-comment wrote 250 events
2018-04-18T20:11:47.996230: Writer writer2-comment used 18.460459947586 seconds, avg 13.542457810359 events/second
2018-04-18T20:11:29.671939: Writer writer3-user started
2018-04-18T20:11:46.670162: Writer writer3-user wrote 250 events
2018-04-18T20:11:46.671499: Writer writer3-user used 16.927097797394 seconds, avg 14.769218149049 events/second
2018-04-18T20:11:29.704792: Writer writer3-post started
2018-04-18T20:11:48.491440: Writer writer3-post wrote 250 events
2018-04-18T20:11:48.491500: Writer writer3-post used 18.692674875259 seconds, avg 13.374222879727 events/second
2018-04-18T20:11:29.627795: Writer writer3-todo started
2018-04-18T20:11:48.538411: Writer writer3-todo wrote 250 events
2018-04-18T20:11:48.538472: Writer writer3-todo used 18.78467297554 seconds, avg 13.308722506137 events/second
2018-04-18T20:11:29.736124: Writer writer3-blog started
2018-04-18T20:11:48.236120: Writer writer3-blog wrote 250 events
2018-04-18T20:11:48.237818: Writer writer3-blog used 18.410712003708 seconds, avg 13.57905114966 events/second
2018-04-18T20:11:29.642747: Writer writer3-comment started
2018-04-18T20:11:48.846435: Writer writer3-comment wrote 250 events
2018-04-18T20:11:48.846502: Writer writer3-comment used 19.12452507019 seconds, avg 13.072220046378 events/second
2018-04-18T20:11:29.788209: Writer writer4-user started
2018-04-18T20:11:47.957431: Writer writer4-user wrote 250 events
2018-04-18T20:11:47.957492: Writer writer4-user used 18.103180170059 seconds, avg 13.80972832682 events/second
2018-04-18T20:11:29.755473: Writer writer4-post started
2018-04-18T20:11:48.509313: Writer writer4-post wrote 250 events
2018-04-18T20:11:48.509388: Writer writer4-post used 18.740933895111 seconds, avg 13.339783460056 events/second
2018-04-18T20:11:29.694331: Writer writer4-todo started
2018-04-18T20:11:47.645776: Writer writer4-todo wrote 250 events
2018-04-18T20:11:47.647576: Writer writer4-todo used 17.8588950634 seconds, avg 13.998626405076 events/second
2018-04-18T20:11:29.658323: Writer writer4-blog started
2018-04-18T20:11:48.957111: Writer writer4-blog wrote 250 events
2018-04-18T20:11:48.957169: Writer writer4-blog used 19.142897129059 seconds, avg 13.059674213079 events/second
2018-04-18T20:11:29.490740: Writer writer4-comment started
2018-04-18T20:11:50.551087: Writer writer4-comment wrote 250 events
2018-04-18T20:11:50.551165: Writer writer4-comment used 21.01029086113 seconds, avg 11.898930940671 events/second
2018-04-18T20:11:29.443623: Writer writer5-user started
2018-04-18T20:11:50.605150: Writer writer5-user wrote 250 events
2018-04-18T20:11:50.605201: Writer writer5-user used 21.094443082809 seconds, avg 11.851462445279 events/second
2018-04-18T20:11:29.618584: Writer writer5-post started
2018-04-18T20:11:50.203383: Writer writer5-post wrote 250 events
2018-04-18T20:11:50.203451: Writer writer5-post used 20.500545024872 seconds, avg 12.194797733265 events/second
2018-04-18T20:11:29.711848: Writer writer5-todo started
2018-04-18T20:11:47.890659: Writer writer5-todo wrote 250 events
2018-04-18T20:11:47.896570: Writer writer5-todo used 18.10062289238 seconds, avg 13.811679381777 events/second
2018-04-18T20:11:29.432976: Writer writer5-blog started
2018-04-18T20:11:50.420804: Writer writer5-blog wrote 250 events
2018-04-18T20:11:50.420868: Writer writer5-blog used 20.898853063583 seconds, avg 11.962378951581 events/second
2018-04-18T20:11:29.616362: Writer writer5-comment started
2018-04-18T20:11:48.423911: Writer writer5-comment wrote 250 events
2018-04-18T20:11:48.423981: Writer writer5-comment used 18.64823102951 seconds, avg 13.406097318527 events/second
2018-04-18T20:11:29.608958: Writer writer6-user started
2018-04-18T20:11:47.297754: Writer writer6-user wrote 250 events
2018-04-18T20:11:47.297831: Writer writer6-user used 17.556140899658 seconds, avg 14.240031532492 events/second
2018-04-18T20:11:29.588896: Writer writer6-post started
2018-04-18T20:11:46.999983: Writer writer6-post wrote 250 events
2018-04-18T20:11:47.000048: Writer writer6-post used 17.299080133438 seconds, avg 14.451635466834 events/second
2018-04-18T20:11:29.638865: Writer writer6-todo started
2018-04-18T20:11:48.815458: Writer writer6-todo wrote 250 events
2018-04-18T20:11:48.815521: Writer writer6-todo used 19.087176084518 seconds, avg 13.097799218334 events/second
2018-04-18T20:11:29.460384: Writer writer6-blog started
2018-04-18T20:11:50.686980: Writer writer6-blog wrote 250 events
2018-04-18T20:11:50.687041: Writer writer6-blog used 21.139849901199 seconds, avg 11.82600638928 events/second
2018-04-18T20:11:29.605031: Writer writer6-comment started
2018-04-18T20:11:47.869592: Writer writer6-comment wrote 250 events
2018-04-18T20:11:47.870002: Writer writer6-comment used 18.115472078323 seconds, avg 13.800357998903 events/second
2018-04-18T20:11:29.408819: Writer writer7-user started
2018-04-18T20:11:50.439092: Writer writer7-user wrote 250 events
2018-04-18T20:11:50.439149: Writer writer7-user used 20.910933017731 seconds, avg 11.955468452222 events/second
2018-04-18T20:11:29.464620: Writer writer7-post started
2018-04-18T20:11:47.865155: Writer writer7-post wrote 250 events
2018-04-18T20:11:47.865227: Writer writer7-post used 18.313792943954 seconds, avg 13.650913317906 events/second
2018-04-18T20:11:29.482796: Writer writer7-todo started
2018-04-18T20:11:48.830302: Writer writer7-todo wrote 250 events
2018-04-18T20:11:48.833489: Writer writer7-todo used 19.256686925888 seconds, avg 12.982503218864 events/second
2018-04-18T20:11:29.595336: Writer writer7-blog started
2018-04-18T20:11:47.639627: Writer writer7-blog wrote 250 events
2018-04-18T20:11:47.639701: Writer writer7-blog used 17.932610034943 seconds, avg 13.941082726545 events/second
2018-04-18T20:11:29.668871: Writer writer7-comment started
2018-04-18T20:11:50.422772: Writer writer7-comment wrote 250 events
2018-04-18T20:11:50.422837: Writer writer7-comment used 20.744226932526 seconds, avg 12.051545753581 events/second
2018-04-18T20:11:29.452662: Writer writer8-user started
2018-04-18T20:11:48.679684: Writer writer8-user wrote 250 events
2018-04-18T20:11:48.679746: Writer writer8-user used 19.113520860672 seconds, avg 13.079746103419 events/second
2018-04-18T20:11:29.711314: Writer writer8-post started
2018-04-18T20:11:47.972462: Writer writer8-post wrote 250 events
2018-04-18T20:11:47.972532: Writer writer8-post used 18.157917022705 seconds, avg 13.768099043926 events/second
2018-04-18T20:11:29.424805: Writer writer8-todo started
2018-04-18T20:11:50.476549: Writer writer8-todo wrote 250 events
2018-04-18T20:11:50.476609: Writer writer8-todo used 20.961013793945 seconds, avg 11.926904035158 events/second
2018-04-18T20:11:29.591877: Writer writer8-blog started
2018-04-18T20:11:48.107817: Writer writer8-blog wrote 250 events
2018-04-18T20:11:48.107877: Writer writer8-blog used 18.403560876846 seconds, avg 13.584327602303 events/second
2018-04-18T20:11:29.634965: Writer writer8-comment started
2018-04-18T20:11:48.729320: Writer writer8-comment wrote 250 events
2018-04-18T20:11:48.729401: Writer writer8-comment used 19.016131877899 seconds, avg 13.146732553457 events/second
2018-04-18T20:11:29.509434: Writer writer9-user started
2018-04-18T20:11:50.408258: Writer writer9-user wrote 250 events
2018-04-18T20:11:50.408322: Writer writer9-user used 20.85232591629 seconds, avg 11.98907023627 events/second
2018-04-18T20:11:29.725211: Writer writer9-post started
2018-04-18T20:11:49.425091: Writer writer9-post wrote 250 events
2018-04-18T20:11:49.425154: Writer writer9-post used 19.601494073868 seconds, avg 12.754129815711 events/second
2018-04-18T20:11:29.471473: Writer writer9-todo started
2018-04-18T20:11:47.322572: Writer writer9-todo wrote 250 events
2018-04-18T20:11:47.322634: Writer writer9-todo used 17.764344930649 seconds, avg 14.073133626711 events/second
2018-04-18T20:11:29.485960: Writer writer9-blog started
2018-04-18T20:11:50.680336: Writer writer9-blog wrote 250 events
2018-04-18T20:11:50.680398: Writer writer9-blog used 21.147696971893 seconds, avg 11.821618227851 events/second
2018-04-18T20:11:29.452327: Writer writer9-comment started
2018-04-18T20:11:47.644470: Writer writer9-comment wrote 250 events
2018-04-18T20:11:47.644538: Writer writer9-comment used 18.137375116348 seconds, avg 13.783692424967 events/second
2018-04-18T20:11:29.770728: Projection projectorsuser started
2018-04-18T20:11:51.774717: Projection projectorsuser read 2500 events
2018-04-18T20:11:51.774777: projection projectorsuser used 21.931951999664 seconds, avg 113.98894179771 events/second
2018-04-18T20:11:29.782009: Projection projectorspost started
2018-04-18T20:11:51.584771: Projection projectorspost read 2500 events
2018-04-18T20:11:51.584854: projection projectorspost used 21.748057126999 seconds, avg 114.95279718097 events/second
2018-04-18T20:11:29.770836: Projection projectorstodo started
2018-04-18T20:11:51.423219: Projection projectorstodo read 2500 events
2018-04-18T20:11:51.423283: projection projectorstodo used 21.614612817764 seconds, avg 115.66249282732 events/second
2018-04-18T20:11:29.719045: Projection projectorsblog started
2018-04-18T20:11:51.419036: Projection projectorsblog read 2500 events
2018-04-18T20:11:51.419113: projection projectorsblog used 21.606006145477 seconds, avg 115.70856655168 events/second
2018-04-18T20:11:29.475216: Projection projectorscomment started
2018-04-18T20:11:51.517005: Projection projectorscomment read 2500 events
2018-04-18T20:11:51.517072: projection projectorscomment used 21.954129934311 seconds, avg 113.87379083026 events/second
2018-04-18T20:11:29.497853: Projection projectorsall started
2018-04-18T20:11:38.411425: Projection projectorsall read 2500 events
2018-04-18T20:11:38.411493: projection projectorsall used 8.8674609661102 seconds, avg 281.92963121626 events/second

arangodb real world test duration 22 seconds
arangodb avg writes 568 events/second
arangodb avg reads 1136 events/second

Dumping logs arangodb!
Attaching to eventstorebenchmarks_database_1
�[36mdatabase_1  |�[0m automaticaly choosing storage engine
�[36mdatabase_1  |�[0m Initializing database...Hang on...
�[36mdatabase_1  |�[0m Database initialized...Starting System...
�[36mdatabase_1  |�[0m 2018-04-18T20:11:03Z [1] INFO ArangoDB 3.2.12 [linux] 64bit, using jemalloc, VPack 0.1.30, RocksDB 5.6.0, ICU 58.1, V8 5.7.492.77, OpenSSL 1.1.0f  25 May 2017
�[36mdatabase_1  |�[0m 2018-04-18T20:11:03Z [1] WARNING {memory} /sys/kernel/mm/transparent_hugepage/enabled is set to 'always'. It is recommended to set it to a value of 'never' or 'madvise'
�[36mdatabase_1  |�[0m 2018-04-18T20:11:03Z [1] WARNING {memory} execute 'sudo bash -c "echo madvise > /sys/kernel/mm/transparent_hugepage/enabled"'
�[36mdatabase_1  |�[0m 2018-04-18T20:11:03Z [1] WARNING {memory} execute 'sudo bash -c "echo madvise > /sys/kernel/mm/transparent_hugepage/defrag"'