[mapserver-dev] 5.6.1 is crashing down be looping the labels

teeschke daniel.teske at web.de
Tue Mar 16 09:46:45 EDT 2010


Hello List..

Comparing the responses between MapServer 5.0.2 (ms4w v2.2.7) and 5.6.1
(ms4w v3.0b10) I found out that MapServer 5.6.1 is crashing down be looping
the labels.

here's my layer definition

LAYER
  DEBUG 5
  NAME 'labels'
  STATUS ON
  TYPE LINE
  CONNECTIONTYPE oraclespatial
  CONNECTION '...'
  DATA "..."
  #FILTER "ROWNUM < 1697" #using a Filter like this, the server doesn't
crash, but not not all Labels get drawed
  METADATA
    'ows_title' 'Labels'
    'wms_srs' 'epsg:31466'
    'gml_featureid' 'ID'
    'gml_include_items' 'NAME,ID'
  END #METADATA
  LABELITEM 'NAME'
  #CLASS #using this Class the lines get painted, without labels and without
crash
    #NAME 'dummy'
    #COLOR 80 80 80
  #END #CLASS
  CLASS
    NAME 'crash it'
    LABEL
      FONT arial
      TYPE TRUETYPE
      SIZE 9
      POSITION AUTO
      #ANGLE FOLLOW #using this, the MapServer works fine, but I need the
labels horizontal
      ANTIALIAS TRUE
      COLOR 0 171 255
      OUTLINECOLOR 255 255 255
    END #LABEL
  END #CLASS
END #LAYER

This is the interesting part of the MapServers "Debug 5"-Report with simple
drawing, without labeling:

[Mon Mar 15 16:38:08 2010].531000 CGI Request 1 on process 4056
[Mon Mar 15 16:38:08 2010].531000 msOracleSpatialLayerOpen called with:
GEOMETRIE FROM MY_LINE_TABLE using unique ID srid 82015 (Layer pointer
01F9D568)
[Mon Mar 15 16:38:08 2010].656000 msOracleSpatialLayerOpen. Shared
connection not available. Creating one.
[Mon Mar 15 16:38:08 2010].656000
msConnPoolRegister(labels,MY_CONNECTION,022A2CA0)
[Mon Mar 15 16:38:08 2010].687000 msOracleSpatialLayerFreeItemInfo was
called.
[Mon Mar 15 16:38:08 2010].687000 msOracleSpatialLayerGetItems was called.
[Mon Mar 15 16:38:08 2010].687000 msOracleSpatialLayerInitItemInfo was
called.
[Mon Mar 15 16:38:08 2010].687000 msOracleSpatialLayerInitItemInfo was
called.
[Mon Mar 15 16:38:08 2010].687000 msOracleSpatialLayerWhichShapes was
called.
[Mon Mar 15 16:38:08 2010].687000 msOracleSpatialLayerWhichShapes. Using
this Sql to retrieve the data: SELECT ID, NAME, GEOMETRIE FROM MY_LINE_TABLE
WHERE SDO_FILTER( GEOMETRIE, MDSYS.SDO_GEOMETRY(2003, :srid,
NULL,MDSYS.SDO_ELEM_INFO_ARRAY(1,1003,3),:ordinates ),'querytype=window') =
'TRUE'
[Mon Mar 15 16:38:08 2010].687000 msOracleSpatialLayerWhichShapes. Bind
values: srid:82015   minx:2434248.916000   miny:5406417.084000 
maxx:2765508.084000   maxy:5670471.916000 
[Mon Mar 15 16:38:08 2010].687000 msOracleSpatialLayerWhichShapes getting
ordinate definition.
[Mon Mar 15 16:38:08 2010].703000 msOracleSpatialLayerWhichShapes converting
to OCIColl.
[Mon Mar 15 16:38:08 2010].703000 msOracleSpatialLayerWhichShapes bind by
name and object.
[Mon Mar 15 16:38:08 2010].703000 msOracleSpatialLayerWhichShapes name and
object now bound.
[Mon Mar 15 16:38:09 2010].93000 msOracleSpatialLayerNextShape on layer
01F9D568, row_num: 0
[Mon Mar 15 16:38:09 2010].93000 msOracleSpatialLayerNextShape on layer
01F9D568, row_num: 1
[Mon Mar 15 16:38:09 2010].93000 msOracleSpatialLayerNextShape on layer
01F9D568, row_num: 2
[Mon Mar 15 16:38:09 2010].93000 msOracleSpatialLayerNextShape on layer
01F9D568, row_num: 3
[...and so on...]
[Mon Mar 15 16:51:12 2010].748000 msOracleSpatialLayerNextShape on layer
01F9D568, row_num: 1024
[Mon Mar 15 16:51:13 2010].29000 msOracleSpatialLayerNextShape on layer
01F9D568, Fetched 1024 more rows (2048 total)
[Mon Mar 15 16:51:13 2010].29000 msOracleSpatialLayerNextShape on layer
01F9D568, row_num: 1025
[...and so on...]
[Mon Mar 15 16:51:13 2010].107000 msOracleSpatialLayerNextShape on layer
01F9D568, row_num: 2048
[Mon Mar 15 16:51:13 2010].529000 msOracleSpatialLayerNextShape on layer
01F9D568, Fetched 1024 more rows (3072 total)
[Mon Mar 15 16:51:13 2010].529000 msOracleSpatialLayerNextShape on layer
01F9D568, row_num: 2049
[...and so on...]
[Mon Mar 15 16:51:13 2010].685000 msOracleSpatialLayerNextShape on layer
01F9D568, row_num: 3072
[Mon Mar 15 16:51:14 2010].60000 msOracleSpatialLayerNextShape on layer
01F9D568, Fetched 1024 more rows (4096 total)
[Mon Mar 15 16:51:14 2010].60000 msOracleSpatialLayerNextShape on layer
01F9D568, row_num: 3073
[...and so on...]
[Mon Mar 15 16:51:14 2010].139000 msOracleSpatialLayerNextShape on layer
01F9D568, row_num: 4096
[Mon Mar 15 16:51:14 2010].342000 msOracleSpatialLayerNextShape on layer
01F9D568, Fetched 1024 more rows (5120 total)
[Mon Mar 15 16:51:14 2010].342000 msOracleSpatialLayerNextShape on layer
01F9D568, row_num: 4097
[...and so on...]
[Mon Mar 15 16:51:14 2010].467000 msOracleSpatialLayerNextShape on layer
01F9D568, row_num: 5120
[Mon Mar 15 16:51:14 2010].748000 msOracleSpatialLayerNextShape on layer
01F9D568, Fetched 1024 more rows (6144 total)
[Mon Mar 15 16:51:14 2010].748000 msOracleSpatialLayerNextShape on layer
01F9D568, row_num: 5121
[...and so on...]
[Mon Mar 15 16:51:14 2010].826000 msOracleSpatialLayerNextShape on layer
01F9D568, row_num: 6144
[Mon Mar 15 16:51:15 2010].60000 msOracleSpatialLayerNextShape on layer
01F9D568, Fetched 1024 more rows (7168 total)
[Mon Mar 15 16:51:15 2010].60000 msOracleSpatialLayerNextShape on layer
01F9D568, row_num: 6145
[...and so on...]
[Mon Mar 15 16:51:15 2010].170000 msOracleSpatialLayerNextShape on layer
01F9D568, row_num: 7168
[Mon Mar 15 16:51:15 2010].373000 msOracleSpatialLayerNextShape on layer
01F9D568, Fetched 815 more rows (7983 total)
[Mon Mar 15 16:51:15 2010].373000 msOracleSpatialLayerNextShape on layer
01F9D568, row_num: 7169
[...and so on...]
[Mon Mar 15 16:38:11 2010].859000 msOracleSpatialLayerNextShape on layer
01F9D568, row_num: 7981
[Mon Mar 15 16:38:11 2010].859000 msOracleSpatialLayerNextShape on layer
01F9D568, row_num: 7982
[Mon Mar 15 16:38:11 2010].859000 msOracleSpatialLayerNextShape on layer
01F9D568, row_num: 7983
[Mon Mar 15 16:38:11 2010].859000 msOracleSpatialLayerNextShape on layer
01F9D568, Fetched 0 more rows (7983 total)
[Mon Mar 15 16:38:11 2010].859000 msOracleSpatialLayerFreeItemInfo was
called.
[Mon Mar 15 16:38:11 2010].859000 msOracleSpatialLayerClose was called.
Layer: 01F9D568, Layer name: labels. Layer connection: MY_CONNECTION
[Mon Mar 15 16:38:11 2010].859000 msOracleSpatialLayerClose. Cleaning
layerinfo handlers.
[Mon Mar 15 16:38:11 2010].859000 msOracleSpatialLayerClose. Release the
Oracle Pool.
[Mon Mar 15 16:38:11 2010].859000
msConnPoolRelease(labels,MY_CONNECTION,022A2CA0)
[Mon Mar 15 16:38:11 2010].859000 msConnPoolClose(MY_CONNECTION,022A2CA0)
[Mon Mar 15 16:38:14 2010].890000 msDrawMap(): Layer 87 (labels), 6.359s
[Mon Mar 15 16:38:14 2010].890000 msDrawMap(): Drawing Label Cache, 0.000s
[Mon Mar 15 16:38:14 2010].890000 msDrawMap() total time: 6.359s
[Mon Mar 15 16:38:15 2010].15000 msSaveImage() total time: 0.125s
[Mon Mar 15 16:38:15 2010].15000 mapserv request processing time (msLoadMap
not incl.): 6.484s
[Mon Mar 15 16:38:15 2010].15000 msFreeMap(): freeing map at 0198B370.
[Mon Mar 15 16:38:15 2010].15000 msOracleSpatialLayerClose was called.
Layer: 01F9D568, Layer name: label. Layer connection: MY_CONNECTION
[Mon Mar 15 16:38:15 2010].15000 freeLayer(): freeing layer at 01F9ABC0.
[Mon Mar 15 16:38:15 2010].15000 freeLayer(): freeing layer at 01F9D568.
[Mon Mar 15 16:38:15 2010].15000 freeLayer(): freeing layer at 01FEC0B8.


And this is the interesting part of the MapServers "Debug 5"-Report with
labeling and crashing:

[Mon Mar 15 16:48:28 2010].375000 CGI Request 1 on process 264
[Mon Mar 15 16:48:28 2010].375000 msOracleSpatialLayerOpen called with:
GEOMETRIE FROM MY_LINE_TABLE using unique ID srid 82015 (Layer pointer
01F9D568)
[Mon Mar 15 16:48:28 2010].485000 msOracleSpatialLayerOpen. Shared
connection not available. Creating one.
[Mon Mar 15 16:48:28 2010].485000
msConnPoolRegister(labels,MY_CONNECTION,022A2CA0)
[Mon Mar 15 16:48:28 2010].500000 msOracleSpatialLayerFreeItemInfo was
called.
[Mon Mar 15 16:48:28 2010].500000 msOracleSpatialLayerGetItems was called.
[Mon Mar 15 16:48:28 2010].500000 msOracleSpatialLayerInitItemInfo was
called.
[Mon Mar 15 16:48:28 2010].500000 msOracleSpatialLayerInitItemInfo was
called.
[Mon Mar 15 16:48:28 2010].500000 msOracleSpatialLayerWhichShapes was
called.
[Mon Mar 15 16:48:28 2010].500000 msOracleSpatialLayerWhichShapes. Using
this Sql to retrieve the data: SELECT ID, NAME, GEOMETRIE FROM MY_LINE_TABLE
WHERE SDO_FILTER( GEOMETRIE, MDSYS.SDO_GEOMETRY(2003, :srid,
NULL,MDSYS.SDO_ELEM_INFO_ARRAY(1,1003,3),:ordinates ),'querytype=window') =
'TRUE'
[Mon Mar 15 16:48:28 2010].500000 msOracleSpatialLayerWhichShapes. Bind
values: srid:82015   minx:2434248.916000   miny:5406417.084000 
maxx:2765508.084000   maxy:5670471.916000 
[Mon Mar 15 16:48:28 2010].500000 msOracleSpatialLayerWhichShapes getting
ordinate definition.
[Mon Mar 15 16:48:28 2010].500000 msOracleSpatialLayerWhichShapes converting
to OCIColl.
[Mon Mar 15 16:48:28 2010].500000 msOracleSpatialLayerWhichShapes bind by
name and object.
[Mon Mar 15 16:48:28 2010].500000 msOracleSpatialLayerWhichShapes name and
object now bound.
[Mon Mar 15 16:48:28 2010].875000 msOracleSpatialLayerNextShape on layer
01F9D568, row_num: 0
[Mon Mar 15 16:48:28 2010].875000 msOracleSpatialLayerNextShape on layer
01F9D568, row_num: 1
[Mon Mar 15 16:48:28 2010].875000 msOracleSpatialLayerNextShape on layer
01F9D568, row_num: 2
[Mon Mar 15 16:48:28 2010].875000 msOracleSpatialLayerNextShape on layer
01F9D568, row_num: 3
[...and so on...]
[Mon Mar 15 16:48:28 2010].985000 msOracleSpatialLayerNextShape on layer
01F9D568, row_num: 1024
[Mon Mar 15 16:48:29 2010].282000 msOracleSpatialLayerNextShape on layer
01F9D568, Fetched 1024 more rows (2048 total)
[Mon Mar 15 16:48:29 2010].282000 msOracleSpatialLayerNextShape on layer
01F9D568, row_num: 1025
[...and so on...]
[Mon Mar 15 16:48:29 2010].297000 msOracleSpatialLayerNextShape on layer
01F9D568, row_num: 1095
[Mon Mar 15 16:48:29 2010].297000 msOracleSpatialLayerNextShape on layer
01F9D568, row_num: 1096
[Mon Mar 15 16:48:29 2010].297000 msOracleSpatialLayerNextShape on layer
01F9D568, row_num: 1097
[Mon Mar 15 16:48:30 2010].407000 msConnPoolClose(): Closing connection ....
even though ref_count=1.
[Mon Mar 15 16:48:30 2010].407000 msConnPoolClose(): General error message.
Closing connection MY_CONNECTION even though ref_count=1.
[Mon Mar 15 16:48:30 2010].407000 msConnPoolClose(MY_CONNECTION,022A2CA0)

Maybe its important, this is the answer of mapserv -v:

MapServer version 5.6.1 OUTPUT=GIF OUTPUT=PNG OUTPUT=JPEG OUTPUT=WBMP
OUTPUT=PDF
 OUTPUT=SWF OUTPUT=SVG SUPPORTS=PROJ SUPPORTS=AGG SUPPORTS=FREETYPE
SUPPORTS=ICO
NV SUPPORTS=FRIBIDI SUPPORTS=WMS_SERVER SUPPORTS=WMS_CLIENT
SUPPORTS=WFS_SERVER
SUPPORTS=WFS_CLIENT SUPPORTS=WCS_SERVER SUPPORTS=SOS_SERVER SUPPORTS=FASTCGI
SUP
PORTS=THREADS SUPPORTS=GEOS SUPPORTS=RGBA_PNG INPUT=JPEG INPUT=POSTGIS
INPUT=ORA
CLESPATIAL INPUT=OGR INPUT=GDAL INPUT=SHAPEFILE

Thanks a lot and regards,
Daniel
-- 
View this message in context: http://n2.nabble.com/5-6-1-is-crashing-down-be-looping-the-labels-tp4743626p4743626.html
Sent from the Mapserver - Dev mailing list archive at Nabble.com.


More information about the mapserver-dev mailing list