[Mapserver-dev] [Fwd: More MapServer test results]

Paul Ramsey pramsey at refractions.net
Mon Sep 20 11:53:03 EDT 2004


This is a multi-part message in MIME format.
--------------070102010004080405020404
Content-Type: text/plain; charset=us-ascii; format=flowed
Content-Transfer-Encoding: 7bit

The news just keeps getting worser and worser.

It appears that the time spent in this render is more or less evenly 
split between pulling the shapes from the SDE server and rendering them 
to the image.

The log below was creating by putting metering in the msDrawMap function 
for vector data, which start/stop timers around layer opening (you can 
see the salutary effect of the connection pooling code in action) around 
WhichShapes (which is not very expensive at all) and around the drawing 
loop.

For the next shape loop, I did three timers, one around the whole loop, 
and two inside the loop, around the nextShape and the drawShape methods. 
For the inside timers I did not do any output, I just summed up the 
times and outputted them at the end, after the completion of the loop.

The "Loaded Time" is the sum of time spent in the nextShape method, and 
the "Drawn Time" is the sum of time spent in the drawShape method.

ArcIMS does this map render in less than half the time Mapserver does 
it. Is it possible that our superior data read speed from shape files 
has been covering up an inferior render speed?

Anyhow, as I see it there are only a few explanations left
- Mapserver (gd) is just abnormally slow at rendering
   - Possibly our gd is compiled with poor or missing optimization
- Our SDE code is pulling way more data than we need, so we
   are wasting a large amount of time on data that does not
   get mapped.
- There is something terrible and mysterious about the
   windows platform that is slowing us down that has not been
   seen before because all the ArcSDE testing was on Linux/UNIX.

Any other thoughts from the gallery? Suggestions? Hints? Profiler ideas?

--------------070102010004080405020404
Content-Type: message/rfc822;
 name="More MapServer test results"
Content-Transfer-Encoding: 7bit
Content-Disposition: inline;
 filename="More MapServer test results"

Return-Path: <banders at refractions.net>
Received: from lion.animals ([unix socket])
	by lion.animals (Cyrus v2.1.9) with LMTP; Mon, 20 Sep 2004 08:38:31 -0700
X-Sieve: CMU Sieve 2.2
Received: by lion.animals (Postfix, from userid 88)
	id BA537E3BA; Mon, 20 Sep 2004 08:38:31 -0700 (PDT)
Received: from [192.168.50.35] (unknown [192.168.50.35])
	by lion.animals (Postfix) with ESMTP id 0F7C5E3B8
	for <pramsey at refractions.net>; Mon, 20 Sep 2004 08:38:30 -0700 (PDT)
Message-ID: <414EFAE0.5080808 at refractions.net>
Date: Mon, 20 Sep 2004 08:44:32 -0700
From: Brock Anderson <banders at refractions.net>
User-Agent: Mozilla Thunderbird 0.6 (Windows/20040502)
X-Accept-Language: en-us, en
MIME-Version: 1.0
To: Paul Ramsey <pramsey at refractions.net>
Subject: More MapServer test results
Content-Type: text/plain; charset=us-ascii; format=flowed
Content-Transfer-Encoding: 7bit
X-Spam-Checker-Version: SpamAssassin 2.63 (2004-01-11) on lion.animals
X-Spam-Status: No, hits=-6.3 required=5.0 tests=BAYES_00,UPPERCASE_25_50 
	autolearn=no version=2.63
X-Spam-Level: 

I used the same URL and .map file as before:
http://slkapps2.env.gov.bc.ca/mapserver/mapserv.exe?map=D:\apps_nt\bcgov\axl\LOPN_test\MapServerCommonService1.map&request=getmap&layers=BC_RIV_LAKE_WET_POLYS_500M,BC_WATER_LINES_500M,BC_TRANSPORT_LINES_500M,BC_PROV_BOUNDARIES_LINES_500M,BC_MANMADE_LINES_500M,BC_MAJOR_CITIES_POINTS_500M,BC_WATER_POINTS_500M,BC_TRANSPORT_POINTS_500M,BC_SPOT_ELEVATION_POINTS_500M,BC_MANMADE_POINTS_500M&version=1.1.1&STYLES=&SRS=EPSG:42102&BBOX=600000,800000,1500000,1300000&WIDTH=1000&HEIGHT=1000&FORMAT=PNG

Total Response time: 86452 ms

Here's the debugging output

[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Started:
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerOpen(BC_RIV_LAKE_WET_POLYS_500M): Start Time: 375.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerOpen(BC_RIV_LAKE_WET_POLYS_500M): End Time: 1187.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerOpen(BC_RIV_LAKE_WET_POLYS_500M): Elapsed Time: 812.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerWhichShapes(BC_RIV_LAKE_WET_POLYS_500M): Start Time: 1203.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerWhichShapes(BC_RIV_LAKE_WET_POLYS_500M): End Time: 1265.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerWhichShapes(BC_RIV_LAKE_WET_POLYS_500M): Elapsed Time: 62.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Looping on msLayerNextShape(BC_RIV_LAKE_WET_POLYS_500M): Start Time: 
1265.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Looping on msLayerNextShape(BC_RIV_LAKE_WET_POLYS_500M): End Time: 
3500.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Looping on msLayerNextShape(BC_RIV_LAKE_WET_POLYS_500M): Elapsed Time: 
2235.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Shapes Loaded(BC_RIV_LAKE_WET_POLYS_500M): 2027
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Shapes Loaded Time(BC_RIV_LAKE_WET_POLYS_500M): 1188.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Shapes Drawn(BC_RIV_LAKE_WET_POLYS_500M): 2019
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Shapes Drawn Time(BC_RIV_LAKE_WET_POLYS_500M): 1047.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerOpen(BC_WATER_LINES_500M): Start Time: 3500.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerOpen(BC_WATER_LINES_500M): End Time: 3515.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerOpen(BC_WATER_LINES_500M): Elapsed Time: 15.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerWhichShapes(BC_WATER_LINES_500M): Start Time: 3531.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerWhichShapes(BC_WATER_LINES_500M): End Time: 3734.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerWhichShapes(BC_WATER_LINES_500M): Elapsed Time: 203.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Looping on msLayerNextShape(BC_WATER_LINES_500M): Start Time: 3734.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Looping on msLayerNextShape(BC_WATER_LINES_500M): End Time: 7969.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Looping on msLayerNextShape(BC_WATER_LINES_500M): Elapsed Time: 4235.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Shapes Loaded(BC_WATER_LINES_500M): 8872
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Shapes Loaded Time(BC_WATER_LINES_500M): 1278.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Shapes Drawn(BC_WATER_LINES_500M): 8871
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Shapes Drawn Time(BC_WATER_LINES_500M): 2877.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerOpen(BC_TRANSPORT_LINES_500M): Start Time: 7969.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerOpen(BC_TRANSPORT_LINES_500M): End Time: 7985.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerOpen(BC_TRANSPORT_LINES_500M): Elapsed Time: 16.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerWhichShapes(BC_TRANSPORT_LINES_500M): Start Time: 8000.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerWhichShapes(BC_TRANSPORT_LINES_500M): End Time: 8047.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerWhichShapes(BC_TRANSPORT_LINES_500M): Elapsed Time: 47.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Looping on msLayerNextShape(BC_TRANSPORT_LINES_500M): Start Time: 
8047.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Looping on msLayerNextShape(BC_TRANSPORT_LINES_500M): End Time: 8625.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Looping on msLayerNextShape(BC_TRANSPORT_LINES_500M): Elapsed Time: 
578.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Shapes Loaded(BC_TRANSPORT_LINES_500M): 905
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Shapes Loaded Time(BC_TRANSPORT_LINES_500M): 265.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Shapes Drawn(BC_TRANSPORT_LINES_500M): 904
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Shapes Drawn Time(BC_TRANSPORT_LINES_500M): 313.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerOpen(BC_PROV_BOUNDARIES_LINES_500M): Start Time: 8625.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerOpen(BC_PROV_BOUNDARIES_LINES_500M): End Time: 8641.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerOpen(BC_PROV_BOUNDARIES_LINES_500M): Elapsed Time: 16.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerWhichShapes(BC_PROV_BOUNDARIES_LINES_500M): Start Time: 8641.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerWhichShapes(BC_PROV_BOUNDARIES_LINES_500M): End Time: 8672.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerWhichShapes(BC_PROV_BOUNDARIES_LINES_500M): Elapsed Time: 31.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Looping on msLayerNextShape(BC_PROV_BOUNDARIES_LINES_500M): Start Time: 
8672.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Looping on msLayerNextShape(BC_PROV_BOUNDARIES_LINES_500M): End Time: 
8688.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Looping on msLayerNextShape(BC_PROV_BOUNDARIES_LINES_500M): Elapsed 
Time: 16.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Shapes Loaded(BC_PROV_BOUNDARIES_LINES_500M): 34
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Shapes Loaded Time(BC_PROV_BOUNDARIES_LINES_500M): 16.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Shapes Drawn(BC_PROV_BOUNDARIES_LINES_500M): 33
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Shapes Drawn Time(BC_PROV_BOUNDARIES_LINES_500M): 0.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerOpen(BC_MANMADE_LINES_500M): Start Time: 8688.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerOpen(BC_MANMADE_LINES_500M): End Time: 8688.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerOpen(BC_MANMADE_LINES_500M): Elapsed Time: 0.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerWhichShapes(BC_MANMADE_LINES_500M): Start Time: 8703.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerWhichShapes(BC_MANMADE_LINES_500M): End Time: 8735.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerWhichShapes(BC_MANMADE_LINES_500M): Elapsed Time: 32.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Looping on msLayerNextShape(BC_MANMADE_LINES_500M): Start Time: 8735.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Looping on msLayerNextShape(BC_MANMADE_LINES_500M): End Time: 8766.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Looping on msLayerNextShape(BC_MANMADE_LINES_500M): Elapsed Time: 31.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Shapes Loaded(BC_MANMADE_LINES_500M): 180
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Shapes Loaded Time(BC_MANMADE_LINES_500M): 31.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Shapes Drawn(BC_MANMADE_LINES_500M): 0
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Shapes Drawn Time(BC_MANMADE_LINES_500M): 0.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerOpen(BC_MAJOR_CITIES_POINTS_500M): Start Time: 8766.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerOpen(BC_MAJOR_CITIES_POINTS_500M): End Time: 8781.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerOpen(BC_MAJOR_CITIES_POINTS_500M): Elapsed Time: 15.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerWhichShapes(BC_MAJOR_CITIES_POINTS_500M): Start Time: 8797.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerWhichShapes(BC_MAJOR_CITIES_POINTS_500M): End Time: 8813.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerWhichShapes(BC_MAJOR_CITIES_POINTS_500M): Elapsed Time: 16.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Looping on msLayerNextShape(BC_MAJOR_CITIES_POINTS_500M): Start Time: 
8813.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Looping on msLayerNextShape(BC_MAJOR_CITIES_POINTS_500M): End Time: 
8828.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Looping on msLayerNextShape(BC_MAJOR_CITIES_POINTS_500M): Elapsed Time: 
15.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Shapes Loaded(BC_MAJOR_CITIES_POINTS_500M): 57
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Shapes Loaded Time(BC_MAJOR_CITIES_POINTS_500M): 0.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Shapes Drawn(BC_MAJOR_CITIES_POINTS_500M): 56
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Shapes Drawn Time(BC_MAJOR_CITIES_POINTS_500M): 15.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerOpen(BC_WATER_POINTS_500M): Start Time: 8828.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerOpen(BC_WATER_POINTS_500M): End Time: 8828.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerOpen(BC_WATER_POINTS_500M): Elapsed Time: 0.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerWhichShapes(BC_WATER_POINTS_500M): Start Time: 8844.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerWhichShapes(BC_WATER_POINTS_500M): End Time: 8860.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerWhichShapes(BC_WATER_POINTS_500M): Elapsed Time: 16.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Looping on msLayerNextShape(BC_WATER_POINTS_500M): Start Time: 8860.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Looping on msLayerNextShape(BC_WATER_POINTS_500M): End Time: 8860.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Looping on msLayerNextShape(BC_WATER_POINTS_500M): Elapsed Time: 0.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Shapes Loaded(BC_WATER_POINTS_500M): 5
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Shapes Loaded Time(BC_WATER_POINTS_500M): 0.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Shapes Drawn(BC_WATER_POINTS_500M): 4
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Shapes Drawn Time(BC_WATER_POINTS_500M): 0.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerOpen(BC_TRANSPORT_POINTS_500M): Start Time: 8860.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerOpen(BC_TRANSPORT_POINTS_500M): End Time: 8860.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerOpen(BC_TRANSPORT_POINTS_500M): Elapsed Time: 0.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerWhichShapes(BC_TRANSPORT_POINTS_500M): Start Time: 8875.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerWhichShapes(BC_TRANSPORT_POINTS_500M): End Time: 8891.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerWhichShapes(BC_TRANSPORT_POINTS_500M): Elapsed Time: 16.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Looping on msLayerNextShape(BC_TRANSPORT_POINTS_500M): Start Time: 
8891.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Looping on msLayerNextShape(BC_TRANSPORT_POINTS_500M): End Time: 
8906.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Looping on msLayerNextShape(BC_TRANSPORT_POINTS_500M): Elapsed Time: 
15.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Shapes Loaded(BC_TRANSPORT_POINTS_500M): 33
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Shapes Loaded Time(BC_TRANSPORT_POINTS_500M): 0.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Shapes Drawn(BC_TRANSPORT_POINTS_500M): 32
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Shapes Drawn Time(BC_TRANSPORT_POINTS_500M): 15.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerOpen(BC_SPOT_ELEVATION_POINTS_500M): Start Time: 8906.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerOpen(BC_SPOT_ELEVATION_POINTS_500M): End Time: 8906.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerOpen(BC_SPOT_ELEVATION_POINTS_500M): Elapsed Time: 0.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerWhichShapes(BC_SPOT_ELEVATION_POINTS_500M): Start Time: 8922.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerWhichShapes(BC_SPOT_ELEVATION_POINTS_500M): End Time: 10063.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerWhichShapes(BC_SPOT_ELEVATION_POINTS_500M): Elapsed Time: 
1141.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Looping on msLayerNextShape(BC_SPOT_ELEVATION_POINTS_500M): Start Time: 
10063.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Looping on msLayerNextShape(BC_SPOT_ELEVATION_POINTS_500M): End Time: 
54645.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Looping on msLayerNextShape(BC_SPOT_ELEVATION_POINTS_500M): Elapsed 
Time: 44582.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Shapes Loaded(BC_SPOT_ELEVATION_POINTS_500M): 20967
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Shapes Loaded Time(BC_SPOT_ELEVATION_POINTS_500M): 3335.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Shapes Drawn(BC_SPOT_ELEVATION_POINTS_500M): 20966
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Shapes Drawn Time(BC_SPOT_ELEVATION_POINTS_500M): 41016.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerOpen(BC_MANMADE_POINTS_500M): Start Time: 54645.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerOpen(BC_MANMADE_POINTS_500M): End Time: 54660.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerOpen(BC_MANMADE_POINTS_500M): Elapsed Time: 15.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerWhichShapes(BC_MANMADE_POINTS_500M): Start Time: 54676.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerWhichShapes(BC_MANMADE_POINTS_500M): End Time: 54707.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
msLayerWhichShapes(BC_MANMADE_POINTS_500M): Elapsed Time: 31.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Looping on msLayerNextShape(BC_MANMADE_POINTS_500M): Start Time: 
54707.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Looping on msLayerNextShape(BC_MANMADE_POINTS_500M): End Time: 56160.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Looping on msLayerNextShape(BC_MANMADE_POINTS_500M): Elapsed Time: 
1453.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Shapes Loaded(BC_MANMADE_POINTS_500M): 315
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Shapes Loaded Time(BC_MANMADE_POINTS_500M): 188.000000
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Shapes Drawn(BC_MANMADE_POINTS_500M): 314
[Mon Sep 20 08:35:02 2004] [error] [client 142.32.21.119] Mapserver 
Shapes Drawn Time(BC_MANMADE_POINTS_500M): 1265.000000

--------------070102010004080405020404--



More information about the mapserver-dev mailing list