[mapserver-dev] 5.6.1 is crashing down be looping the labels
Alan Boudreault
aboudreault at mapgears.com
Wed Mar 17 13:04:35 EDT 2010
Hi Daniel,
Please create a ticket with the layer information and attach the debug output
in a file. Have you the same problem with MapServer 5.4.2 ?
thanks,
Alan
On March 16, 2010 09:46:45 am teeschke wrote:
> 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
>
--
Alan Boudreault
Mapgears
http://www.mapgears.com
More information about the mapserver-dev
mailing list