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

Steve Lime steve.lime at dnr.state.mn.us
Mon Sep 20 17:54:59 EDT 2004


Yup, that'd do it. Label collision can be very expensive. You need to
place
each label and make sure there's nothing already there. It's just bbox
checking
but with a ton of points...

At least you can do it and it didn't crash- a nice test at that. 

I wonder how long ArcIMS would take drawing the points as labels...

Steve

>>> Paul Ramsey <pramsey at refractions.net> 9/20/2004 11:31:18 AM >>>
GOOD NEWS FOR A CHANGE!

I spoke too soon when I said the log only contained bad news. In fact,
in contained one very very telling line:

[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

Whoa!!! We are spending 3 seconds loading elevation points and 41
seconds rendering them!!

So, we look at the map file. Lo, the elevation points are being
rendered
as labels, and there is collision detection going on. Looks like the
collision detection algorithm is quite expensive.

Turning this layer from labels to a simple symbol, Mapserver is now
running 1-2 seconds faster than ArcIMS, even with the 1 second ArcIMS
connection overhead. (ArcIMS was not doing collision detection on
these
points.)

So, it looks like FastCGI connection pooling alone (and some good
inspection of the map files going in) will allow us to meet-or-beat
ArcIMS in terms of performance.

More news later,

Paul

Paul Ramsey wrote:

> 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?
> 
>
------------------------------------------------------------------------
> 
> Subject:
> More MapServer test results
> From:
> Brock Anderson <banders at refractions.net>
> Date:
> Mon, 20 Sep 2004 08:44:32 -0700
> To:
> Paul Ramsey <pramsey at refractions.net>
> 
> To:
> Paul Ramsey <pramsey at refractions.net>
> 
> 
> 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



_______________________________________________
Mapserver-dev mailing list
Mapserver-dev at lists.gis.umn.edu 
http://lists.gis.umn.edu/mailman/listinfo/mapserver-dev



More information about the mapserver-dev mailing list