[Chameleon] performance of drawmap.php

[Daniel Morissette]

bartvde at xs4all.nl wrote:
> 
> Paul, there must be something pretty slow in Mapserver WMS client or not?
> Or can you think of any other cause for this? Can Daniel provide any
> insight into this?
> 

I'm assuming that you're using MapServer 4.2, correct?

You could see where the time is spent by setting DEBUG TRUE in the 
client mapfile at the map level and inside each layer of interest.  You 
also need a build with --enable-debug on Linux/Unix, or with 
-DENABLE_STDERR_DEBUG -DNEED_NONBLOCKING_STDERR on Windows.  I don't 
know if our windows builds on maptools include that option or not, Paul 
should know.

The Apache error_log will contain detailed times for each layer and for 
the full map draw. I copied below an example where we request 7 layers 
from the GMap WMS.  As you'll notice, a single WMS client request is 
issued because MapServer detects that all layers come from the same 
server and combines them.

Daniel
-- 
------------------------------------------------------------
  Daniel Morissette               dmorissette at dmsolutions.ca
  DM Solutions Group              http://www.dmsolutions.ca/
------------------------------------------------------------


[Tue Jun  1 13:44:24 2004].426143 HTTP: Starting to prepare HTTP requests.
[Tue Jun  1 13:44:24 2004].426337 HTTP request: id=0, 
http://www2.dmsolutions.ca/cgi-bin/mswms_gmap?LAYERS=bathymetry,land%5Ffn,drain%5Ffn,drainage,prov%5Fbound,fedlimit,popplace&REQUEST=GetMap&SERVICE=WMS&FORMAT=image%2Fpng&STYLES=,,,,,,&HEIGHT=300&VERSION=1.1.0&SRS=EPSG:42304&WIDTH=400&BBOX=%2D2594560%2E000000%2C%2D712631%2E000000%2C3467360%2E000000%2C3840000%2E000000&TRANSPARENT=TRUE&EXCEPTIONS=application/vnd.ogc.se_inimage
[Tue Jun  1 13:44:24 2004].426500 HTTP: Before download loop
[Tue Jun  1 13:44:26 2004].162388 msHTTPWriteFct(id=0, 1214 bytes)
[Tue Jun  1 13:44:26 2004].170452 msHTTPWriteFct(id=0, 1440 bytes)
...
...
[Tue Jun  1 13:44:26 2004].681604 msHTTPWriteFct(id=0, 1006 bytes)
[Tue Jun  1 13:44:26 2004].681638 msHTTPWriteFct(id=0, 427 bytes)
[Tue Jun  1 13:44:26 2004].694859 msHTTPWriteFct(id=0, 1284 bytes)
[Tue Jun  1 13:44:26 2004].790004 HTTP: After download loop
[Tue Jun  1 13:44:26 2004].790045 msHTTPExecuteRequests() timing summary 
per layer (connect_time + time_to_first_packet + download_time = 
total_time in seconds)
[Tue Jun  1 13:44:26 2004].790178 Layer 0: 0.124 + 1.613 + 0.655 = 2.392s
[Tue Jun  1 13:44:26 2004].790414 msDrawRasterLayerLow(l0:bathymetry): 
entering.
[Tue Jun  1 13:44:26 2004].825814 msDrawMap(): Layer 0 (l0:bathymetry), 
0.035s
[Tue Jun  1 13:44:26 2004].825898 msDrawMap(): Layer 1 (l1:land_fn), 0.000s
[Tue Jun  1 13:44:26 2004].825918 msDrawMap(): Layer 3 (l3:drain_fn), 0.000s
[Tue Jun  1 13:44:26 2004].825936 msDrawMap(): Layer 4 (l4:drainage), 0.000s
[Tue Jun  1 13:44:26 2004].825953 msDrawMap(): Layer 5 (l5:prov_bound), 
0.000s
[Tue Jun  1 13:44:26 2004].825971 msDrawMap(): Layer 6 (l6:fedlimit), 0.000s
[Tue Jun  1 13:44:26 2004].825990 msDrawMap(): Layer 9 (l9:popplace), 0.000s
[Tue Jun  1 13:44:26 2004].826009 msDrawMap(): Drawing Label Cache, 0.000s
[Tue Jun  1 13:44:26 2004].826029 msDrawMap() total time: 3.045s