alacarted-boost-log

ak@bsd102:~/alacarte/build % make
-- Boost version: 1.55.0
-- Found the following Boost libraries:
--   system
--   filesystem
--   thread
--   unit_test_framework
--   program_options
--   serialization
--   regex
--   iostreams
--   log
--   log_setup
-- Could NOT find Doxygen (missing:  DOXYGEN_EXECUTABLE)
-- Enabling debug code: 0
-- Default:
-- Debug: -g
-- Profile: -g -O0 -ftest-coverage -fprofile-arcs -fprofile-values -fvpt -pg
-- Release: -O3 -DNDEBUG
-- Sanitize: -g -fsanitize=thread -fno-omit-frame-pointer
-- Configuring done
-- Generating done
-- Build files have been written to: /home/ak/alacarte/build
[ 18%] Built target alacarte-importer
[100%] Built target alacarte-server

ak@bsd102:~/alacarte/build % make tests
[  8%] Built target unitTests_utils
[ 13%] Built target alacarte-importer
[ 38%] Built target unitTests_parser
[ 45%] Built target unitTests_general
[ 51%] Built target unitTests_importer
[ 76%] Built target unitTests_server
[ 76%] Linking CXX executable unitTests_eval
[100%] Built target unitTests_eval
[100%] Built target tests

ak@bsd102:~/alacarte/build % ./
./: Permission denied.

ak@bsd102:~/alacarte/build % ./unitTests_
unitTests_eval*     unitTests_general*  unitTests_importer* unitTests_parser*   unitTests_server*   unitTests_utils*
ak@bsd102:~/alacarte/build % ./unitTests_eval
Running 47 test cases...

*** No errors detected
ak@bsd102:~/alacarte/build % ./unitTests_general
Running 12 test cases...
[2015-10-18 20:24:01.624225] [0x0000000080a80640] [info]    Load geodata from "/home/ak/alacarte/tests/data/input/karlsruhe_big.carte"
[2015-10-18 20:24:01.911656] [0x0000000080a80640] [info]    Load geodata from "/home/ak/alacarte/tests/data/input/karlsruhe_big.carte"
[2015-10-18 20:24:02.194304] [0x0000000080a80640] [info]    Load geodata from "/home/ak/alacarte/tests/data/input/karlsruhe_big.carte"
[2015-10-18 20:24:02.481838] [0x0000000080a80640] [info]    Load geodata from "/home/ak/alacarte/tests/data/input/karlsruhe_big.carte"
[2015-10-18 20:24:02.767582] [0x0000000080a80640] [info]    Load geodata from "/home/ak/alacarte/tests/data/input/karlsruhe_big.carte"
[2015-10-18 20:24:03.096488] [0x0000000080a80640] [info]    Load geodata from "/home/ak/alacarte/tests/data/input/karlsruhe_big.carte"
[2015-10-18 20:24:03.388885] [0x0000000080a80640] [info]    Load geodata from "/home/ak/alacarte/tests/data/input/karlsruhe_big.carte"
[2015-10-18 20:24:03.733448] [0x0000000080a80640] [info]    Load geodata from "/home/ak/alacarte/tests/data/input/karlsruhe_big.carte"
[2015-10-18 20:24:04.062106] [0x0000000080a80640] [info]    Load geodata from "/home/ak/alacarte/tests/data/input/karlsruhe_big.carte"
[2015-10-18 20:24:04.400945] [0x0000000080a80640] [info]    Load geodata from "/home/ak/alacarte/tests/data/input/karlsruhe_big.carte"
[2015-10-18 20:24:04.729719] [0x0000000080a80640] [info]    Load geodata from "/home/ak/alacarte/tests/data/input/karlsruhe_big.carte"
[2015-10-18 20:24:05.061407] [0x0000000080a80640] [info]    Load geodata from "/home/ak/alacarte/tests/data/input/karlsruhe_big.carte"

*** No errors detected
ak@bsd102:~/alacarte/build % ./unitTests_importer
Running 5 test cases...
[2015-10-18 20:24:14.668180] [0x0000000080ac0640] [info]    Load xml-file "/home/ak/alacarte/tests/data/output/importer/tmp1.xml"
[2015-10-18 20:24:14.668400] [0x0000000080ac0640] [info]    File size is 5kb
[2015-10-18 20:24:14.668449] [0x0000000080ac0640] [info]    Loading [99%]
[2015-10-18 20:24:14.671280] [0x0000000080ac0640] [info]    Load xml-file "/home/ak/alacarte/tests/data/output/importer/tmp2.xml"
[2015-10-18 20:24:14.671622] [0x0000000080ac0640] [info]    File size is 12kb
[2015-10-18 20:24:14.671735] [0x0000000080ac0640] [info]    Loading [99%]
[2015-10-18 20:24:14.675196] [0x0000000080ac0640] [info]    Load xml-file "/home/ak/alacarte/tests/data/output/importer/tmp3.xml"
[2015-10-18 20:24:14.675319] [0x0000000080ac0640] [info]    File size is 12kb
[2015-10-18 20:24:14.675438] [0x0000000080ac0640] [info]    Loading [99%]
[2015-10-18 20:24:14.678268] [0x0000000080ac0640] [info]    Load xml-file "/home/ak/alacarte/tests/data/output/importer/tmp4.xml"
[2015-10-18 20:24:14.678707] [0x0000000080ac0640] [info]    File size is 5kb
[2015-10-18 20:24:14.678937] [0x0000000080ac0640] [info]    Loading [99%]
[2015-10-18 20:24:14.681176] [0x0000000080ac0640] [info]    Load xml-file "/home/ak/alacarte/tests/data/output/importer/tmp5.xml"
[2015-10-18 20:24:14.681589] [0x0000000080ac0640] [info]    File size is 11kb
[2015-10-18 20:24:14.681783] [0x0000000080ac0640] [info]    Loading [99%]

*** No errors detected
ak@bsd102:~/alacarte/build % ./unitTests_parser
Running 33 test cases...
[2015-10-18 20:24:21.857309] [0x0000000080b00640] [info]    Load stylesheet[/home/ak/alacarte/tests/data/input/parser/mapcss/attr_1.txt]
[2015-10-18 20:24:21.860147] [0x0000000080b00640] [info]    Load stylesheet[/home/ak/alacarte/tests/data/input/parser/mapcss/canvas_1.txt]
[2015-10-18 20:24:21.861088] [0x0000000080b00640] [info]    Load stylesheet[/home/ak/alacarte/tests/data/input/parser/mapcss/canvas_2.txt]
[2015-10-18 20:24:21.861582] [0x0000000080b00640] [info]    Load stylesheet[/home/ak/alacarte/tests/data/input/parser/mapcss/check_empty_1.txt]
[2015-10-18 20:24:21.861980] [0x0000000080b00640] [info]    Load stylesheet[/home/ak/alacarte/tests/data/input/parser/mapcss/empty_rule_1.txt]
[2015-10-18 20:24:21.862352] [0x0000000080b00640] [info]    Load stylesheet[/home/ak/alacarte/tests/data/input/parser/mapcss/entities_1.txt]
[2015-10-18 20:24:21.863545] [0x0000000080b00640] [info]    Load stylesheet[/home/ak/alacarte/tests/data/input/parser/mapcss/entities_2.txt]
[2015-10-18 20:24:21.864103] [0x0000000080b00640] [info]    Load stylesheet[/home/ak/alacarte/tests/data/input/parser/mapcss/entities_3.txt]
[2015-10-18 20:24:21.865076] [0x0000000080b00640] [info]    Load stylesheet[/home/ak/alacarte/tests/data/input/parser/mapcss/entities_4.txt]
[2015-10-18 20:24:21.865519] [0x0000000080b00640] [info]    Load stylesheet[/home/ak/alacarte/tests/data/input/parser/mapcss/entities_5.txt]
[2015-10-18 20:24:21.866002] [0x0000000080b00640] [info]    Load stylesheet[/home/ak/alacarte/tests/data/input/parser/mapcss/senseless_1.txt]
[2015-10-18 20:24:21.867420] [0x0000000080b00640] [warning] In line 0 could be an eval but failed to parse so.
[2015-10-18 20:24:21.867784] [0x0000000080b00640] [warning] Expression is used as NSt3__112basic_stringIcNS_11char_traitsIcEENS_9allocatorIcEEEE!
[2015-10-18 20:24:21.867862] [0x0000000080b00640] [warning] ''

*** No errors detected
ak@bsd102:~/alacarte/build % ./unitTests_utils
Running 79 test cases...

*** No errors detected
ak@bsd102:~/alacarte/build % ./unitTests_server
Running 33 test cases...
[2015-10-18 20:24:42.652309] [0x0000000080b00640] [info]    Load geodata from "/home/ak/alacarte/tests/data/input/karlsruhe_big.carte"
[2015-10-18 20:24:43.073038] [0x0000000080b00880] [info]    Load stylesheet[/home/ak/alacarte/tests/../data/mapcss/default.mapcss]
[2015-10-18 20:24:43.122419] [0x0000000080b008c0] [info]    Load stylesheet[/home/ak/alacarte/tests/../data/mapcss/wire.mapcss]
[2015-10-18 20:24:43.155897] [0x0000000080b00840] [debug]   Stylesheetcache default created.
[2015-10-18 20:24:43.156616] [0x0000000080b00840] [debug]   readFile: Not found: cache/default/0/0/0.png
[2015-10-18 20:24:43.340180] [0x0000000080b00840] [debug]   Stylesheetcache wire created.
[2015-10-18 20:24:43.340633] [0x0000000080b00840] [debug]   readFile: Not found: cache/wire/0/0/0.png
[2015-10-18 20:24:43.467760] [0x0000000080b00840] [info]    Prerendering finished in 00:0
[2015-10-18 20:24:47.320890] [0x0000000080b00840] [info]    Prerendering finished in 00:4
[2015-10-18 20:24:50.191123] [0x0000000080b00640] [info]
Cache:

ComputeRect:

GeoNodes:
2 Measurements on Zoom: 0, average: 1.158 ms
1 Measurements on Zoom: 17, average: 144 µs
4 Measurements on Zoom: 18, average: 72.25 µs

GeoWays:
2 Measurements on Zoom: 0, average: 243 µs
1 Measurements on Zoom: 17, average: 132 µs
4 Measurements on Zoom: 18, average: 103.75 µs

GeoRelation:
2 Measurements on Zoom: 0, average: 9 µs
1 Measurements on Zoom: 17, average: 12 µs
4 Measurements on Zoom: 18, average: 7.75 µs

StylesheetMatch:
2 Measurements on Zoom: 0, average: 80.8625 ms
1 Measurements on Zoom: 17, average: 12.168 ms
4 Measurements on Zoom: 18, average: 3.28325 ms

Renderer:
2 Measurements on Zoom: 0, average: 52.621 ms
1 Measurements on Zoom: 17, average: 759.763 ms
4 Measurements on Zoom: 18, average: 28.3547 ms

GeoContainsData:
2 Measurements on Zoom: 0, average: 143.5 µs
1 Measurements on Zoom: 17, average: 14 µs
4 Measurements on Zoom: 18, average: 57.25 µs

Slicing:
2 Measurements on Zoom: 0, average: 17.605 ms
1 Measurements on Zoom: 17, average: 251.034 ms
4 Measurements on Zoom: 18, average: 250.59 ms

[2015-10-18 20:24:50.191563] [0x0000000080b00640] [debug]   RequestManager destructed
[2015-10-18 20:24:50.233361] [0x0000000080b00640] [info]    Load geodata from "/home/ak/alacarte/tests/data/input/karlsruhe_big.carte"
[2015-10-18 20:24:50.515356] [0x0000000080b00900] [info]    Load stylesheet[/home/ak/alacarte/tests/../data/mapcss/default.mapcss]
[2015-10-18 20:24:50.579970] [0x0000000080b00800] [debug]   Stylesheetcache default created.
[2015-10-18 20:24:50.580197] [0x0000000080b00780] [info]    Load stylesheet[/home/ak/alacarte/tests/../data/mapcss/wire.mapcss]
[2015-10-18 20:24:50.620421] [0x0000000080b00800] [debug]   readFile: Not found: cache/default/0/0/0.png
[2015-10-18 20:24:50.800601] [0x0000000080b00800] [debug]   Stylesheetcache wire created.
[2015-10-18 20:24:50.800978] [0x0000000080b00800] [debug]   readFile: Not found: cache/wire/0/0/0.png
[2015-10-18 20:24:50.915559] [0x0000000080b00800] [info]    Prerendering finished in 00:0
[2015-10-18 20:24:52.626230] [0x0000000080b00640] [info]
Cache:

ComputeRect:

GeoNodes:
2 Measurements on Zoom: 0, average: 912 µs

GeoWays:
2 Measurements on Zoom: 0, average: 139.5 µs

GeoRelation:
2 Measurements on Zoom: 0, average: 7.5 µs

StylesheetMatch:
2 Measurements on Zoom: 0, average: 81.4145 ms

Renderer:
2 Measurements on Zoom: 0, average: 43.619 ms

GeoContainsData:
2 Measurements on Zoom: 0, average: 50.5 µs

Slicing:
2 Measurements on Zoom: 0, average: 18.8095 ms

[2015-10-18 20:24:52.629802] [0x0000000080b00640] [info]    Load geodata from "/home/ak/alacarte/tests/data/input/karlsruhe_big.carte"
[2015-10-18 20:24:52.913254] [0x0000000080b00940] [info]    Load stylesheet[/home/ak/alacarte/tests/../data/mapcss/default.mapcss]
[2015-10-18 20:24:52.963985] [0x0000000080b00840] [debug]   Stylesheetcache default created.
[2015-10-18 20:24:52.964163] [0x0000000080b00a40] [info]    Load stylesheet[/home/ak/alacarte/tests/../data/mapcss/wire.mapcss]
[2015-10-18 20:24:52.999518] [0x0000000080b00ac0] [info]    Server is now waiting for new connections ...
[2015-10-18 20:24:52.999670] [0x0000000080b00ac0] [info]    Send a "SIGINT", "SIGTERM" or "SIGQUIT" signal to shut down the server. (ctrl-c)
[2015-10-18 20:24:52.999839] [0x0000000080b00840] [debug]   readFile: Not found: cache/default/0/0/0.png
[2015-10-18 20:24:53.375167] [0x0000000080b00840] [info]    127.0.0.1 - [18/10/2015:20:24:53] "GET default/13/4289/2813.png HTTP/1.0" 200 63297
[2015-10-18 20:24:53.375705] [0x0000000080b00840] [info]    Answered "default/13/4289/2813.png"
[2015-10-18 20:24:53.377551] [0x0000000080b00640] [info]
Cache:

ComputeRect:

GeoNodes:
1 Measurements on Zoom: 0, average: 762 µs

GeoWays:
1 Measurements on Zoom: 0, average: 48 µs

GeoRelation:
1 Measurements on Zoom: 0, average: 7 µs

StylesheetMatch:
1 Measurements on Zoom: 0, average: 131.72 ms

Renderer:
1 Measurements on Zoom: 0, average: 23.87 ms

GeoContainsData:
1 Measurements on Zoom: 0, average: 8 µs

Slicing:
1 Measurements on Zoom: 0, average: 21.772 ms

[2015-10-18 20:24:53.609207] [0x0000000080b00640] [info]    Load geodata from "/home/ak/alacarte/tests/data/input/karlsruhe_big.carte"
[2015-10-18 20:24:53.884251] [0x0000000080b00a80] [info]    Load stylesheet[/home/ak/alacarte/tests/../data/mapcss/default.mapcss]
[2015-10-18 20:24:53.935011] [0x0000000080b00800] [debug]   Stylesheetcache default created.
[2015-10-18 20:24:53.935178] [0x0000000080b00c00] [info]    Load stylesheet[/home/ak/alacarte/tests/../data/mapcss/wire.mapcss]
[2015-10-18 20:24:53.971155] [0x0000000080b00c80] [info]    Server is now waiting for new connections ...
[2015-10-18 20:24:53.971508] [0x0000000080b00c80] [info]    Send a "SIGINT", "SIGTERM" or "SIGQUIT" signal to shut down the server. (ctrl-c)
[2015-10-18 20:24:53.971787] [0x0000000080b00800] [debug]   readFile: Not found: cache/default/0/0/0.png
[2015-10-18 20:24:54.689535] [0x0000000080b00800] [info]    127.0.0.1 - [18/10/2015:20:24:54] "GET default/13/4286/2812.png HTTP/1.0" 200 113618
[2015-10-18 20:24:54.690019] [0x0000000080b00800] [info]    Answered "default/13/4286/2812.png"
[2015-10-18 20:24:54.693299] [0x0000000080b00640] [info]
Cache:

ComputeRect:

GeoNodes:
1 Measurements on Zoom: 0, average: 988 µs

GeoWays:
1 Measurements on Zoom: 0, average: 208 µs

GeoRelation:
1 Measurements on Zoom: 0, average: 7 µs

StylesheetMatch:
1 Measurements on Zoom: 0, average: 135.302 ms

Renderer:
1 Measurements on Zoom: 0, average: 24.603 ms

GeoContainsData:
1 Measurements on Zoom: 0, average: 10 µs

Slicing:
1 Measurements on Zoom: 0, average: 23.059 ms

[2015-10-18 20:24:54.841631] [0x0000000080b00640] [info]    Load geodata from "/home/ak/alacarte/tests/data/input/karlsruhe_big.carte"
[2015-10-18 20:24:55.113495] [0x0000000080b00c40] [info]    Load stylesheet[/home/ak/alacarte/tests/../data/mapcss/default.mapcss]
[2015-10-18 20:24:55.162145] [0x0000000080b00840] [debug]   Stylesheetcache default created.
[2015-10-18 20:24:55.162392] [0x0000000080b00ec0] [info]    Load stylesheet[/home/ak/alacarte/tests/../data/mapcss/wire.mapcss]
[2015-10-18 20:24:55.202279] [0x0000000080b00f40] [info]    Server is now waiting for new connections ...
[2015-10-18 20:24:55.202440] [0x0000000080b00f40] [info]    Send a "SIGINT", "SIGTERM" or "SIGQUIT" signal to shut down the server. (ctrl-c)
[2015-10-18 20:24:55.202677] [0x0000000080b00840] [debug]   readFile: Not found: cache/default/0/0/0.png
[2015-10-18 20:24:55.382873] [0x0000000080b00840] [info]    MalformedURLException: Not enough arguments. Url: Invalid/Url
[2015-10-18 20:24:55.384380] [0x0000000080b00840] [info]    127.0.0.1 - [18/10/2015:20:24:55] "GET Invalid/Url HTTP/1.0" 403 22837
[2015-10-18 20:24:55.384480] [0x0000000080b00840] [info]    Answered "Invalid/Url"
[2015-10-18 20:24:55.384767] [0x0000000080b00640] [info]
Cache:

ComputeRect:

GeoNodes:
1 Measurements on Zoom: 0, average: 1.019 ms

GeoWays:
1 Measurements on Zoom: 0, average: 414 µs

GeoRelation:
1 Measurements on Zoom: 0, average: 22 µs

StylesheetMatch:
1 Measurements on Zoom: 0, average: 131.111 ms

Renderer:
1 Measurements on Zoom: 0, average: 23.502 ms

GeoContainsData:
1 Measurements on Zoom: 0, average: 9 µs

Slicing:
1 Measurements on Zoom: 0, average: 22.187 ms

[2015-10-18 20:24:55.388585] [0x0000000080b00640] [info]    Load geodata from "/home/ak/alacarte/tests/data/input/karlsruhe_big.carte"
[2015-10-18 20:24:55.658294] [0x0000000080b00f00] [info]    Load stylesheet[/home/ak/alacarte/tests/../data/mapcss/default.mapcss]
[2015-10-18 20:24:55.706965] [0x0000000080b00800] [debug]   Stylesheetcache default created.
[2015-10-18 20:24:55.707189] [0x0000000080b01040] [info]    Load stylesheet[/home/ak/alacarte/tests/../data/mapcss/wire.mapcss]
[2015-10-18 20:24:55.745650] [0x0000000080b010c0] [info]    Server is now waiting for new connections ...
[2015-10-18 20:24:55.745925] [0x0000000080b010c0] [info]    Send a "SIGINT", "SIGTERM" or "SIGQUIT" signal to shut down the server. (ctrl-c)
[2015-10-18 20:24:55.746190] [0x0000000080b00800] [debug]   readFile: Not found: cache/default/0/0/0.png
[2015-10-18 20:24:55.848052] [0x0000000080b010c0] [info]    127.0.0.1 - [18/10/2015:20:24:55] "GET (Space)Invalid HTTP/0.0" 400 22837
[2015-10-18 20:24:55.848221] [0x0000000080b010c0] [info]    Answered "(Space)Invalid"
[2015-10-18 20:24:55.848474] [0x0000000080b00640] [info]
Cache:

ComputeRect:

GeoNodes:

GeoWays:

GeoRelation:

StylesheetMatch:

Renderer:

GeoContainsData:

Slicing:

[2015-10-18 20:24:55.940118] [0x0000000080b00640] [info]    Load geodata from "/home/ak/alacarte/tests/data/input/karlsruhe_big.carte"
[2015-10-18 20:24:56.211968] [0x0000000080b01080] [info]    Load stylesheet[/home/ak/alacarte/tests/../data/mapcss/default.mapcss]
[2015-10-18 20:24:56.272661] [0x0000000080b00840] [debug]   Stylesheetcache default created.
[2015-10-18 20:24:56.273041] [0x0000000080b011c0] [info]    Load stylesheet[/home/ak/alacarte/tests/../data/mapcss/wire.mapcss]
[2015-10-18 20:24:56.316460] [0x0000000080b01240] [info]    Server is now waiting for new connections ...
[2015-10-18 20:24:56.316609] [0x0000000080b01240] [info]    Send a "SIGINT", "SIGTERM" or "SIGQUIT" signal to shut down the server. (ctrl-c)
[2015-10-18 20:24:56.316760] [0x0000000080b00840] [debug]   readFile: Not found: cache/default/0/0/0.png
[2015-10-18 20:24:56.684815] [0x0000000080b00840] [info]    127.0.0.1 - [18/10/2015:20:24:56] "GET default/15/17148/11253.png HTTP/1.0" 200 88915
[2015-10-18 20:24:56.685195] [0x0000000080b00840] [info]    Answered "default/15/17148/11253.png"
[2015-10-18 20:24:56.705288] [0x0000000080b00640] [info]
Cache:

ComputeRect:

GeoNodes:
1 Measurements on Zoom: 0, average: 872 µs

GeoWays:
1 Measurements on Zoom: 0, average: 183 µs

GeoRelation:
1 Measurements on Zoom: 0, average: 6 µs

StylesheetMatch:
1 Measurements on Zoom: 0, average: 137.733 ms

Renderer:
1 Measurements on Zoom: 0, average: 23.664 ms

GeoContainsData:
1 Measurements on Zoom: 0, average: 50 µs

Slicing:
1 Measurements on Zoom: 0, average: 22.703 ms

[2015-10-18 20:24:56.916561] [0x0000000080b00640] [info]    Load geodata from "/home/ak/alacarte/tests/data/input/karlsruhe_big.carte"
[2015-10-18 20:24:57.185410] [0x0000000080b01200] [info]    Load stylesheet[/home/ak/alacarte/tests/../data/mapcss/default.mapcss]
[2015-10-18 20:24:57.233706] [0x0000000080b00800] [debug]   Stylesheetcache default created.
[2015-10-18 20:24:57.234000] [0x0000000080b01440] [info]    Load stylesheet[/home/ak/alacarte/tests/../data/mapcss/wire.mapcss]
[2015-10-18 20:24:57.268695] [0x0000000080b014c0] [info]    Server is now waiting for new connections ...
[2015-10-18 20:24:57.269475] [0x0000000080b014c0] [info]    Send a "SIGINT", "SIGTERM" or "SIGQUIT" signal to shut down the server. (ctrl-c)
[2015-10-18 20:24:57.269764] [0x0000000080b00800] [debug]   readFile: Not found: cache/default/0/0/0.png
[2015-10-18 20:24:57.458975] [0x0000000080b00800] [info]    UnknownImageFormatException: Unknown image format. Url: default/15/17148/11253.gif
[2015-10-18 20:24:57.460481] [0x0000000080b00800] [info]    127.0.0.1 - [18/10/2015:20:24:57] "GET default/15/17148/11253.gif HTTP/1.0" 501 22837
[2015-10-18 20:24:57.460642] [0x0000000080b00800] [info]    Answered "default/15/17148/11253.gif"
[2015-10-18 20:24:57.460962] [0x0000000080b00640] [info]
Cache:

ComputeRect:

GeoNodes:
1 Measurements on Zoom: 0, average: 1.206 ms

GeoWays:
1 Measurements on Zoom: 0, average: 193 µs

GeoRelation:
1 Measurements on Zoom: 0, average: 6 µs

StylesheetMatch:
1 Measurements on Zoom: 0, average: 139.146 ms

Renderer:
1 Measurements on Zoom: 0, average: 24.302 ms

GeoContainsData:
1 Measurements on Zoom: 0, average: 24 µs

Slicing:
1 Measurements on Zoom: 0, average: 22.198 ms

[2015-10-18 20:24:57.463615] [0x0000000080b00640] [info]    Load geodata from "/home/ak/alacarte/tests/data/input/karlsruhe_big.carte"
[2015-10-18 20:24:57.742776] [0x0000000080b01480] [info]    Load stylesheet[/home/ak/alacarte/tests/../data/mapcss/default.mapcss]
[2015-10-18 20:24:57.802719] [0x0000000080b00840] [debug]   Stylesheetcache default created.
[2015-10-18 20:24:57.803368] [0x0000000080b015c0] [info]    Load stylesheet[/home/ak/alacarte/tests/../data/mapcss/wire.mapcss]
[2015-10-18 20:24:57.838067] [0x0000000080b67d80] [info]    Server is now waiting for new connections ...
[2015-10-18 20:24:57.838211] [0x0000000080b67d80] [info]    Send a "SIGINT", "SIGTERM" or "SIGQUIT" signal to shut down the server. (ctrl-c)
[2015-10-18 20:24:57.838374] [0x0000000080b00840] [debug]   readFile: Not found: cache/default/0/0/0.png
[2015-10-18 20:24:58.015454] [0x0000000080b00840] [info]    UnknownImageFormatException: Unknown image format. Url: default/15/17148/11253.jpg
[2015-10-18 20:24:58.016992] [0x0000000080b00840] [info]    127.0.0.1 - [18/10/2015:20:24:58] "GET default/15/17148/11253.jpg HTTP/1.0" 501 22837
[2015-10-18 20:24:58.017089] [0x0000000080b00840] [info]    Answered "default/15/17148/11253.jpg"
[2015-10-18 20:24:58.017441] [0x0000000080b00640] [info]
Cache:

ComputeRect:

GeoNodes:
1 Measurements on Zoom: 0, average: 710 µs

GeoWays:
1 Measurements on Zoom: 0, average: 181 µs

GeoRelation:
1 Measurements on Zoom: 0, average: 6 µs

StylesheetMatch:
1 Measurements on Zoom: 0, average: 128.354 ms

Renderer:
1 Measurements on Zoom: 0, average: 23.461 ms

GeoContainsData:
1 Measurements on Zoom: 0, average: 8 µs

Slicing:
1 Measurements on Zoom: 0, average: 22.399 ms

[2015-10-18 20:24:58.020247] [0x0000000080b00640] [info]    Load geodata from "/home/ak/alacarte/tests/data/input/karlsruhe_big.carte"
[2015-10-18 20:24:58.298293] [0x0000000080b67d40] [info]    Load stylesheet[/home/ak/alacarte/tests/../data/mapcss/default.mapcss]
[2015-10-18 20:24:58.348555] [0x0000000080b00800] [debug]   Stylesheetcache default created.
[2015-10-18 20:24:58.348866] [0x0000000080cdc9c0] [info]    Load stylesheet[/home/ak/alacarte/tests/../data/mapcss/wire.mapcss]
[2015-10-18 20:24:58.383875] [0x0000000080d1d900] [info]    Server is now waiting for new connections ...
[2015-10-18 20:24:58.383985] [0x0000000080d1d900] [info]    Send a "SIGINT", "SIGTERM" or "SIGQUIT" signal to shut down the server. (ctrl-c)
[2015-10-18 20:24:58.384194] [0x0000000080b00800] [debug]   readFile: Not found: cache/default/0/0/0.png
[2015-10-18 20:24:58.578534] [0x0000000080b00800] [info]    UnknownImageFormatException: Unknown image format. Url: default/15/17148/11253.jpeg
[2015-10-18 20:24:58.580519] [0x0000000080b00800] [info]    127.0.0.1 - [18/10/2015:20:24:58] "GET default/15/17148/11253.jpeg HTTP/1.0" 501 22837
[2015-10-18 20:24:58.580620] [0x0000000080b00800] [info]    Answered "default/15/17148/11253.jpeg"
[2015-10-18 20:24:58.580937] [0x0000000080b00640] [info]
Cache:

ComputeRect:

GeoNodes:
1 Measurements on Zoom: 0, average: 911 µs

GeoWays:
1 Measurements on Zoom: 0, average: 326 µs

GeoRelation:
1 Measurements on Zoom: 0, average: 20 µs

StylesheetMatch:
1 Measurements on Zoom: 0, average: 144.409 ms

Renderer:
1 Measurements on Zoom: 0, average: 24.161 ms

GeoContainsData:
1 Measurements on Zoom: 0, average: 21 µs

Slicing:
1 Measurements on Zoom: 0, average: 22.07 ms

[2015-10-18 20:24:58.583156] [0x0000000080b00640] [info]    Load geodata from "/home/ak/alacarte/tests/data/input/karlsruhe_big.carte"
[2015-10-18 20:24:58.858009] [0x0000000080d1d8c0] [info]    Load stylesheet[/home/ak/alacarte/tests/../data/mapcss/default.mapcss]
[2015-10-18 20:24:58.908068] [0x0000000080b00840] [debug]   Stylesheetcache default created.
[2015-10-18 20:24:58.908322] [0x0000000080d1da00] [info]    Load stylesheet[/home/ak/alacarte/tests/../data/mapcss/wire.mapcss]
[2015-10-18 20:24:58.946218] [0x0000000080d1da80] [info]    Server is now waiting for new connections ...
[2015-10-18 20:24:58.946379] [0x0000000080d1da80] [info]    Send a "SIGINT", "SIGTERM" or "SIGQUIT" signal to shut down the server. (ctrl-c)
[2015-10-18 20:24:58.946566] [0x0000000080b00840] [debug]   readFile: Not found: cache/default/0/0/0.png
[2015-10-18 20:24:59.221688] [0x0000000080b00840] [info]    127.0.0.1 - [18/10/2015:20:24:59] "GET default/15/17148/11253.svg HTTP/1.0" 200 0
[2015-10-18 20:24:59.221816] [0x0000000080b00840] [info]    Answered "default/15/17148/11253.svg"
[2015-10-18 20:24:59.222188] [0x0000000080b00640] [info]
Cache:

ComputeRect:

GeoNodes:
1 Measurements on Zoom: 0, average: 899 µs

GeoWays:
1 Measurements on Zoom: 0, average: 171 µs

GeoRelation:
1 Measurements on Zoom: 0, average: 9 µs

StylesheetMatch:
1 Measurements on Zoom: 0, average: 145.769 ms

Renderer:
1 Measurements on Zoom: 0, average: 23.36 ms

GeoContainsData:
1 Measurements on Zoom: 0, average: 10 µs

Slicing:
1 Measurements on Zoom: 0, average: 22.234 ms

[2015-10-18 20:24:59.226195] [0x0000000080b00640] [info]    Load geodata from "/home/ak/alacarte/tests/data/input/karlsruhe_big.carte"
[2015-10-18 20:24:59.500992] [0x0000000080d1da40] [info]    Load stylesheet[/home/ak/alacarte/tests/../data/mapcss/default.mapcss]
[2015-10-18 20:24:59.550631] [0x0000000080b00800] [debug]   Stylesheetcache default created.
[2015-10-18 20:24:59.551014] [0x0000000081d2b840] [info]    Load stylesheet[/home/ak/alacarte/tests/../data/mapcss/wire.mapcss]
[2015-10-18 20:24:59.586168] [0x0000000081d2b8c0] [info]    Server is now waiting for new connections ...
[2015-10-18 20:24:59.586380] [0x0000000081d2b8c0] [info]    Send a "SIGINT", "SIGTERM" or "SIGQUIT" signal to shut down the server. (ctrl-c)
[2015-10-18 20:24:59.586935] [0x0000000080b00800] [debug]   readFile: Not found: cache/default/0/0/0.png
[2015-10-18 20:24:59.777135] [0x0000000080b00800] [info]    UnknownImageFormatException: Unknown image format. Url: default/15/17148/11253.svgz
[2015-10-18 20:24:59.779063] [0x0000000080b00800] [info]    127.0.0.1 - [18/10/2015:20:24:59] "GET default/15/17148/11253.svgz HTTP/1.0" 501 22837
[2015-10-18 20:24:59.779164] [0x0000000080b00800] [info]    Answered "default/15/17148/11253.svgz"
[2015-10-18 20:24:59.779509] [0x0000000080b00640] [info]
Cache:

ComputeRect:

GeoNodes:
1 Measurements on Zoom: 0, average: 977 µs

GeoWays:
1 Measurements on Zoom: 0, average: 0 µs

GeoRelation:
1 Measurements on Zoom: 0, average: 1 µs

StylesheetMatch:
1 Measurements on Zoom: 0, average: 139.833 ms

Renderer:
1 Measurements on Zoom: 0, average: 24.812 ms

GeoContainsData:
1 Measurements on Zoom: 0, average: 30 µs

Slicing:
1 Measurements on Zoom: 0, average: 22.416 ms

[2015-10-18 20:24:59.782031] [0x0000000080b00640] [info]    Load geodata from "/home/ak/alacarte/tests/data/input/karlsruhe_big.carte"
[2015-10-18 20:25:00.063239] [0x0000000081d2b880] [info]    Load stylesheet[/home/ak/alacarte/tests/../data/mapcss/default.mapcss]
[2015-10-18 20:25:00.122892] [0x0000000080b00840] [debug]   Stylesheetcache default created.
[2015-10-18 20:25:00.123575] [0x0000000080b00840] [debug]   readFile: Not found: cache/default/0/0/0.png
[2015-10-18 20:25:00.123912] [0x0000000081d2b9c0] [info]    Load stylesheet[/home/ak/alacarte/tests/../data/mapcss/wire.mapcss]
[2015-10-18 20:25:00.188561] [0x0000000081d2ba40] [info]    Server is now waiting for new connections ...
[2015-10-18 20:25:00.200373] [0x0000000081d2ba40] [info]    Send a "SIGINT", "SIGTERM" or "SIGQUIT" signal to shut down the server. (ctrl-c)
[2015-10-18 20:25:00.354290] [0x0000000080b00840] [info]    UnknownImageFormatException: Unknown image format. Url: default/15/17148/11253.somefile
[2015-10-18 20:25:00.356241] [0x0000000080b00840] [info]    127.0.0.1 - [18/10/2015:20:25:0] "GET default/15/17148/11253.somefile HTTP/1.0" 501 22837
[2015-10-18 20:25:00.356613] [0x0000000080b00840] [info]    Answered "default/15/17148/11253.somefile"
[2015-10-18 20:25:00.357094] [0x0000000080b00640] [info]
Cache:

ComputeRect:

GeoNodes:
1 Measurements on Zoom: 0, average: 860 µs

GeoWays:
1 Measurements on Zoom: 0, average: 182 µs

GeoRelation:
1 Measurements on Zoom: 0, average: 8 µs

StylesheetMatch:
1 Measurements on Zoom: 0, average: 144.302 ms

Renderer:
1 Measurements on Zoom: 0, average: 24.27 ms

GeoContainsData:
1 Measurements on Zoom: 0, average: 8 µs

Slicing:
1 Measurements on Zoom: 0, average: 22.62 ms

[2015-10-18 20:25:00.528340] [0x0000000080b00640] [info]    Load geodata from "/home/ak/alacarte/tests/data/input/renderer_test.carte"
/home/ak/alacarte/tests/shared/compare.cpp(114): error in "fill": check !changed failed
[2015-10-18 20:25:00.549852] [0x0000000080b00640] [info]    Load geodata from "/home/ak/alacarte/tests/data/input/renderer_test.carte"
/home/ak/alacarte/tests/shared/compare.cpp(114): error in "line": check !changed failed
[2015-10-18 20:25:00.570122] [0x0000000080b00640] [info]    Load geodata from "/home/ak/alacarte/tests/data/input/renderer_test.carte"
/home/ak/alacarte/tests/shared/compare.cpp(114): error in "linecaps": check !changed failed
[2015-10-18 20:25:00.590072] [0x0000000080b00640] [info]    Load geodata from "/home/ak/alacarte/tests/data/input/renderer_test.carte"
/home/ak/alacarte/tests/shared/compare.cpp(114): error in "linejoins": check !changed failed
[2015-10-18 20:25:00.613992] [0x0000000080b00640] [info]    Load geodata from "/home/ak/alacarte/tests/data/input/renderer_test.carte"
/home/ak/alacarte/tests/shared/compare.cpp(114): error in "dashes": check !changed failed
[2015-10-18 20:25:00.637791] [0x0000000080b00640] [info]    Load geodata from "/home/ak/alacarte/tests/data/input/renderer_test.carte"
/home/ak/alacarte/tests/shared/compare.cpp(114): error in "icon_size": check !changed failed
[2015-10-18 20:25:00.657150] [0x0000000080b00640] [info]    Load geodata from "/home/ak/alacarte/tests/data/input/renderer_test.carte"
/home/ak/alacarte/tests/shared/compare.cpp(114): error in "shield_node_1": check !changed failed
[2015-10-18 20:25:00.682074] [0x0000000080b00640] [info]    Load geodata from "/home/ak/alacarte/tests/data/input/renderer_test.carte"
/home/ak/alacarte/tests/shared/compare.cpp(114): error in "shield_node_2": check !changed failed
[2015-10-18 20:25:00.709041] [0x0000000080b00640] [info]    Load geodata from "/home/ak/alacarte/tests/data/input/renderer_test.carte"
/home/ak/alacarte/tests/shared/compare.cpp(114): error in "shield_way": check !changed failed
[2015-10-18 20:25:00.737065] [0x0000000080b00640] [info]    Load geodata from "/home/ak/alacarte/tests/data/input/renderer_test.carte"
/home/ak/alacarte/tests/shared/compare.cpp(114): error in "text_node": check !changed failed
[2015-10-18 20:25:00.758170] [0x0000000080b00640] [info]    Load geodata from "/home/ak/alacarte/tests/data/input/renderer_test.carte"
/home/ak/alacarte/tests/shared/compare.cpp(114): error in "text_way": check !changed failed
[2015-10-18 20:25:00.789807] [0x0000000080b00640] [info]    Load geodata from "/home/ak/alacarte/tests/data/input/renderer_test.carte"
/home/ak/alacarte/tests/shared/compare.cpp(114): error in "text_find": check !changed failed
[2015-10-18 20:25:00.822099] [0x0000000080b00640] [debug]   Stylesheetcache default created.
[2015-10-18 20:25:00.822312] [0x0000000080b00640] [debug]   readFile: Not found: cache/default/0/0/0.png
[2015-10-18 20:25:00.822676] [0x0000000080b00640] [debug]   Stylesheetcache default created.
[2015-10-18 20:25:00.822900] [0x0000000080b00640] [debug]   readFile: Not found: cache/default/1/200/1.png
[2015-10-18 20:25:00.823188] [0x0000000080b00640] [debug]   WriteFile: Image not yet rendered TileIdentifier(x=1, y=10, z=15, css=default, format=png)
[2015-10-18 20:25:00.823404] [0x0000000080b00640] [debug]   Deleting least recently used Tile.TileIdentifier(x=1, y=1, z=15, css=default, format=png)
[2015-10-18 20:25:00.823528] [0x0000000080b00640] [debug]   Deleting least recently used Tile.TileIdentifier(x=1, y=2, z=15, css=default, format=png)
[2015-10-18 20:25:00.823742] [0x0000000080b00640] [debug]   Deleting least recently used Tile.TileIdentifier(x=1, y=3, z=15, css=default, format=png)
[2015-10-18 20:25:00.823927] [0x0000000080b00640] [debug]   Deleting least recently used Tile.TileIdentifier(x=1, y=4, z=15, css=default, format=png)
[2015-10-18 20:25:00.824044] [0x0000000080b00640] [debug]   Deleting least recently used Tile.TileIdentifier(x=1, y=5, z=15, css=default, format=png)
[2015-10-18 20:25:00.824173] [0x0000000080b00640] [debug]   Deleting least recently used Tile.TileIdentifier(x=1, y=6, z=15, css=default, format=png)
[2015-10-18 20:25:00.824302] [0x0000000080b00640] [debug]   Deleting least recently used Tile.TileIdentifier(x=1, y=7, z=15, css=default, format=png)
[2015-10-18 20:25:00.824429] [0x0000000080b00640] [debug]   Deleting least recently used Tile.TileIdentifier(x=1, y=8, z=15, css=default, format=png)
[2015-10-18 20:25:00.824552] [0x0000000080b00640] [debug]   Deleting least recently used Tile.TileIdentifier(x=1, y=9, z=15, css=default, format=png)
[2015-10-18 20:25:00.824831] [0x0000000080b00640] [debug]   Deleting least recently used Tile.TileIdentifier(x=1, y=10, z=15, css=default, format=png)
[2015-10-18 20:25:00.825023] [0x0000000080b00640] [debug]   Deleting least recently used Tile.TileIdentifier(x=200, y=1, z=1, css=default, format=png)
[2015-10-18 20:25:00.825146] [0x0000000080b00640] [debug]   Deleting least recently used Tile.TileIdentifier(x=1, y=11, z=15, css=default, format=png)
[2015-10-18 20:25:00.825341] [0x0000000080b00640] [debug]   Deleting least recently used Tile.TileIdentifier(x=1, y=12, z=15, css=default, format=png)
[2015-10-18 20:25:00.825474] [0x0000000080b00640] [debug]   Deleting least recently used Tile.TileIdentifier(x=1, y=13, z=15, css=default, format=png)
[2015-10-18 20:25:00.825729] [0x0000000080b00640] [debug]   Deleting least recently used Tile.TileIdentifier(x=1, y=14, z=15, css=default, format=png)
[2015-10-18 20:25:00.825922] [0x0000000080b00640] [debug]   Deleting least recently used Tile.TileIdentifier(x=1, y=15, z=15, css=default, format=png)
[2015-10-18 20:25:00.826039] [0x0000000080b00640] [debug]   Deleting least recently used Tile.TileIdentifier(x=1, y=16, z=15, css=default, format=png)
[2015-10-18 20:25:00.826168] [0x0000000080b00640] [debug]   Deleting least recently used Tile.TileIdentifier(x=1, y=17, z=15, css=default, format=png)
[2015-10-18 20:25:00.826296] [0x0000000080b00640] [debug]   Deleting least recently used Tile.TileIdentifier(x=1, y=18, z=15, css=default, format=png)
[2015-10-18 20:25:00.826514] [0x0000000080b00640] [debug]   Deleting least recently used Tile.TileIdentifier(x=1, y=19, z=15, css=default, format=png)
[2015-10-18 20:25:00.826619] [0x0000000080b00640] [debug]   Deleting least recently used Tile.TileIdentifier(x=1, y=1, z=15, css=default, format=png)
[2015-10-18 20:25:00.826745] [0x0000000080b00640] [debug]   Deleting least recently used Tile.TileIdentifier(x=1, y=2, z=15, css=default, format=png)
[2015-10-18 20:25:00.826845] [0x0000000080b00640] [debug]   Deleting least recently used Tile.TileIdentifier(x=1, y=3, z=15, css=default, format=png)
[2015-10-18 20:25:00.826975] [0x0000000080b00640] [debug]   Deleting least recently used Tile.TileIdentifier(x=1, y=4, z=15, css=default, format=png)
[2015-10-18 20:25:00.827176] [0x0000000080b00640] [debug]   Deleting least recently used Tile.TileIdentifier(x=1, y=5, z=15, css=default, format=png)
[2015-10-18 20:25:00.827319] [0x0000000080b00640] [debug]   Deleting least recently used Tile.TileIdentifier(x=1, y=6, z=15, css=default, format=png)
[2015-10-18 20:25:00.827497] [0x0000000080b00640] [debug]   Deleting least recently used Tile.TileIdentifier(x=1, y=7, z=15, css=default, format=png)
[2015-10-18 20:25:00.827635] [0x0000000080b00640] [debug]   Deleting least recently used Tile.TileIdentifier(x=1, y=8, z=15, css=default, format=png)
[2015-10-18 20:25:00.827755] [0x0000000080b00640] [debug]   Deleting least recently used Tile.TileIdentifier(x=1, y=9, z=15, css=default, format=png)
/home/ak/alacarte/tests/server/cache-test/cache_test.cpp(84): error in "testToGetATile": check boost::filesystem::exists(config->get<string>(opt::server::cache_path) + "/default/1-200-1.png") failed
[2015-10-18 20:25:00.828866] [0x0000000080b00640] [debug]   Deleting least recently used Tile.TileIdentifier(x=1, y=10, z=15, css=default, format=png)

*** 13 failures detected in test suite "server test"
ak@bsd102:~/alacarte/build %