Re: [geomesa-users] WMS 1.1.0 getMap call Hangs

[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index] [List Home]
Re: [geomesa-users] WMS 1.1.0 getMap call Hangs
From: Chris Snider <chris.snider@xxxxxxxxxx>
Date: Fri, 28 Mar 2014 21:21:01 +0000
Accept-language: en-US
Delivered-to: geomesa-users@xxxxxxxxxxxxxxxx
List-archive: <https://www.locationtech.org/mhonarc/lists/geomesa-users>
List-help: <mailto:geomesa-users-request@locationtech.org?subject=help>
List-subscribe: <http://www.locationtech.org/mailman/listinfo/geomesa-users>, <mailto:geomesa-users-request@locationtech.org?subject=subscribe>
List-unsubscribe: <http://www.locationtech.org/mailman/options/geomesa-users>, <mailto:geomesa-users-request@locationtech.org?subject=unsubscribe>
Thread-index: Ac9KwVzjOUhfp5jrRKmfEyWkhe1R1AAAwOaAAAEZOKA=
Thread-topic: [geomesa-users] WMS 1.1.0 getMap call Hangs
Hi Chris,

The basic logs don't have anything.  Console logging for Production level plus the geomesa logging I have turned on has
Mar 28, 2014 2:58:32 PM org.apache.catalina.core.StandardWrapperValve invoke
INFO: [AccumuloFeatureReader]
  Query:  Query:
   feature type: StateCapitals
   filter: FastBBOX [property=the_geom, envelope=ReferencedEnvelope[-90.24169921875 : -36.89208984375, -4.15283203125 : 27.2241210
9375]]
   [properties: the_geom]
  Polygon:  POLYGON ((-90.24169921875 -4.15283203125, -90.24169921875 27.22412109375, -54.1799851904335 27.22412109375, -54.179985
1904335 -4.15283203125, -90.24169921875 -4.15283203125))
  Are query-polygon and bounds disjoint?  false
  Interval:  null
  GeoHash precision:  6
  ECQL:  None

DEBUG [pool-2-thread-1-SendThread(server:2181)] (ClientCnxn.java:758) - Got ping response for sessionid: 0x444f5f589321f46 after 0ms
DEBUG [pool-2-thread-1-SendThread(server:2181)] (ClientCnxn.java:758) - Got ping response for sessionid: 0x444f5f589321f46 after 0ms
DEBUG [pool-2-thread-1-SendThread(server:2181)] (ClientCnxn.java:758) - Got ping response for sessionid: 0x444f5f589321f46 after 0ms
DEBUG [pool-2-thread-1-SendThread(server:2181)] (ClientCnxn.java:758) - Got ping response for sessionid: 0x444f5f589321f46 after 1ms
DEBUG [pool-2-thread-1-SendThread(server:2181)] (ClientCnxn.java:758) - Got ping response for sessionid: 0x444f5f589321f46 after 1ms
DEBUG [pool-2-thread-1-SendThread(server:2181)] (ClientCnxn.java:758) - Got ping response for sessionid: 0x444f5f589321f46 after 1ms
DEBUG [pool-2-thread-1-SendThread(server:2181)] (ClientCnxn.java:758) - Got ping response for sessionid: 0x444f5f589321f46 after 0ms
DEBUG [pool-2-thread-1-SendThread(server:2181)] (ClientCnxn.java:758) - Got ping response for sessionid: 0x444f5f589321f46 after 1ms
DEBUG [pool-2-thread-1-SendThread(server:2181)] (ClientCnxn.java:758) - Got ping response for sessionid: 0x444f5f589321f46 after 1ms

I changed GeoServer logging to VERBOSE, cleaned the log files out and got this

2014-03-28 15:10:45,257 INFO [geoserver.wms] - 
Request: getMap
	Filter = [Filter.INCLUDE]
	Time = []
	Buffer = 0
	Format = application/openlayers
	Filters = [Filter.INCLUDE]
	Height = 700
	Width = 1200
	Exceptions = SE_XML
	Crs = GEOGCS["WGS 84", 
  DATUM["World Geodetic System 1984", 
    SPHEROID["WGS 84", 6378137.0, 298.257223563, AUTHORITY["EPSG","7030"]], 
    AUTHORITY["EPSG","6326"]], 
  PRIMEM["Greenwich", 0.0, AUTHORITY["EPSG","8901"]], 
  UNIT["degree", 0.017453292519943295], 
  AXIS["Geodetic longitude", EAST], 
  AXIS["Geodetic latitude", NORTH], 
  AUTHORITY["EPSG","4326"]]
	Bbox = ReferencedEnvelope[-180.0 : 180.0, -90.0 : 90.0]
	Env = {}
	Angle = 0.0
	CQLFilter = [Filter.INCLUDE]
	Elevation = []
	FeatureId = null
	Tiled = false
	Palette = null
	SldBody = null
	Sld = null
	Transparent = false
	ValidateSchema = false
	SldVersion = null
	TilesOrigin = null
	FeatureVersion = null
	Layers = [org.geoserver.wms.MapLayerInfo@57f54b6e]
	RemoteOwsType = null
	RemoteOwsURL = null
	FormatOptions = {}
	StartIndex = null
	ViewParams = null
	MaxFeatures = null
	SRS = EPSG:4326
	Styles = [StyleImpl[ name=point]]
	BgColor = java.awt.Color[r=255,g=255,b=255]
	Version = 1.1.1
	Request = GetMap
	Get = true
	RawKvp = {BBOX=-180.0,-90.0,180.0,90.0, VERSION=1.1.1, FORMAT=application/openlayers, SERVICE=WMS, HEIGHT=700, REQUEST=GetMap, LAYERS=BASE:StateCapitals, STYLES=, WIDTH=1200, SRS=EPSG:4326}
	BaseUrl = https://iss203371:8443/geoserver/
	RequestCharset = UTF-8
2014-03-28 15:10:45,621 DEBUG [geoserver.security] - preAuthenticatedPrincipal = admin, trying to authenticate
2014-03-28 15:10:45,621 DEBUG [geoserver.security] - preAuthenticatedPrincipal = admin, trying to authenticate
2014-03-28 15:10:45,622 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/OpenLayers.js]
2014-03-28 15:10:45,622 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/theme/default/style.css]
2014-03-28 15:10:45,622 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/OpenLayers.js]
2014-03-28 15:10:45,622 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/OpenLayers.js]
2014-03-28 15:10:45,622 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/theme/default/style.css]
2014-03-28 15:10:45,623 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/OpenLayers.js]
2014-03-28 15:10:45,623 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/theme/default/style.css]
2014-03-28 15:10:45,623 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/OpenLayers.js]
2014-03-28 15:10:45,623 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/OpenLayers.js]
2014-03-28 15:10:45,623 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/theme/default/style.css]
2014-03-28 15:10:45,623 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/theme/default/style.css]
2014-03-28 15:10:45,623 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/theme/default/style.css]
2014-03-28 15:10:45,623 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/OpenLayers.js]
2014-03-28 15:10:45,623 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/theme/default/style.css]
2014-03-28 15:10:45,733 DEBUG [geoserver.security] - preAuthenticatedPrincipal = admin, trying to authenticate
2014-03-28 15:10:45,733 DEBUG [geoserver.security] - preAuthenticatedPrincipal = admin, trying to authenticate
2014-03-28 15:10:45,733 DEBUG [geoserver.security] - preAuthenticatedPrincipal = admin, trying to authenticate
2014-03-28 15:10:45,734 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/cancel.png]
2014-03-28 15:10:45,734 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/options.png]
2014-03-28 15:10:45,734 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/east-mini.png]
2014-03-28 15:10:45,734 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/cancel.png]
2014-03-28 15:10:45,734 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/options.png]
2014-03-28 15:10:45,734 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/cancel.png]
2014-03-28 15:10:45,734 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/options.png]
2014-03-28 15:10:45,734 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/east-mini.png]
2014-03-28 15:10:45,734 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/cancel.png]
2014-03-28 15:10:45,735 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/options.png]
2014-03-28 15:10:45,735 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/east-mini.png]
2014-03-28 15:10:45,735 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/cancel.png]
2014-03-28 15:10:45,735 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/options.png]
2014-03-28 15:10:45,735 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/cancel.png]
2014-03-28 15:10:45,735 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/options.png]
2014-03-28 15:10:45,735 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/cancel.png]
2014-03-28 15:10:45,735 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/options.png]
2014-03-28 15:10:45,735 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/east-mini.png]
2014-03-28 15:10:45,735 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/east-mini.png]
2014-03-28 15:10:45,736 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/east-mini.png]
2014-03-28 15:10:45,736 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/east-mini.png]
2014-03-28 15:10:45,953 DEBUG [geoserver.security] - preAuthenticatedPrincipal = admin, trying to authenticate
2014-03-28 15:10:45,953 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/south-mini.png]
2014-03-28 15:10:45,953 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/south-mini.png]
2014-03-28 15:10:45,954 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/south-mini.png]
2014-03-28 15:10:45,954 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/south-mini.png]
2014-03-28 15:10:45,954 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/south-mini.png]
2014-03-28 15:10:45,954 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/south-mini.png]
2014-03-28 15:10:45,954 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/south-mini.png]
2014-03-28 15:10:45,955 DEBUG [geoserver.security] - preAuthenticatedPrincipal = admin, trying to authenticate
2014-03-28 15:10:45,956 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/north-mini.png]
2014-03-28 15:10:45,956 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/north-mini.png]
2014-03-28 15:10:45,956 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/north-mini.png]
2014-03-28 15:10:45,956 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/north-mini.png]
2014-03-28 15:10:45,957 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/north-mini.png]
2014-03-28 15:10:45,957 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/north-mini.png]
2014-03-28 15:10:45,957 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/north-mini.png]
2014-03-28 15:10:45,995 DEBUG [geoserver.security] - preAuthenticatedPrincipal = admin, trying to authenticate
2014-03-28 15:10:45,995 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/west-mini.png]
2014-03-28 15:10:45,995 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/west-mini.png]
2014-03-28 15:10:45,995 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/west-mini.png]
2014-03-28 15:10:45,995 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/west-mini.png]
2014-03-28 15:10:45,996 DEBUG [geoserver.security] - preAuthenticatedPrincipal = admin, trying to authenticate
2014-03-28 15:10:45,996 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/west-mini.png]
2014-03-28 15:10:45,996 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/west-mini.png]
2014-03-28 15:10:45,996 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/west-mini.png]
2014-03-28 15:10:45,995 DEBUG [geoserver.security] - preAuthenticatedPrincipal = admin, trying to authenticate
2014-03-28 15:10:45,996 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/zoom-minus-mini.png]
2014-03-28 15:10:45,996 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/slider.png]
2014-03-28 15:10:45,996 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/zoom-minus-mini.png]
2014-03-28 15:10:45,997 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/slider.png]
2014-03-28 15:10:45,998 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/zoom-minus-mini.png]
2014-03-28 15:10:45,998 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/slider.png]
2014-03-28 15:10:45,998 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/zoom-minus-mini.png]
2014-03-28 15:10:45,998 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/slider.png]
2014-03-28 15:10:45,998 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/slider.png]
2014-03-28 15:10:45,998 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/zoom-minus-mini.png]
2014-03-28 15:10:45,999 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/slider.png]
2014-03-28 15:10:45,999 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/zoom-minus-mini.png]
2014-03-28 15:10:45,999 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/slider.png]
2014-03-28 15:10:45,999 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/zoom-minus-mini.png]
2014-03-28 15:10:46,001 DEBUG [geoserver.security] - preAuthenticatedPrincipal = admin, trying to authenticate
2014-03-28 15:10:46,002 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/zoom-plus-mini.png]
2014-03-28 15:10:46,002 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/zoom-plus-mini.png]
2014-03-28 15:10:46,002 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/zoom-plus-mini.png]
2014-03-28 15:10:46,002 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/zoom-plus-mini.png]
2014-03-28 15:10:46,002 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/zoom-plus-mini.png]
2014-03-28 15:10:46,003 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/zoom-plus-mini.png]
2014-03-28 15:10:46,003 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/zoom-plus-mini.png]
2014-03-28 15:10:46,018 DEBUG [geoserver.security] - preAuthenticatedPrincipal = admin, trying to authenticate
2014-03-28 15:10:46,019 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/BASE/wms]
2014-03-28 15:10:46,020 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/BASE/wms]
2014-03-28 15:10:46,020 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/BASE/wms]
2014-03-28 15:10:46,021 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/BASE/wms]
2014-03-28 15:10:46,021 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/BASE/wms]
2014-03-28 15:10:46,021 DEBUG [ows.OWSHandlerMapping] - Mapping [/BASE/wms] to HandlerExecutionChain with handler [org.geoserver.ows.Dispatcher@7690029e] and 1 interceptor
2014-03-28 15:10:46,024 DEBUG [config.impl] - Could not locate service of type interface org.geoserver.wms.WMSInfo in workspace WorkspaceInfoImpl[BASE], available services were [WCSInfoImpl[WCS], WFSInfoImpl[WFS], WMSInfoImpl[WMS], WPSInfoImpl[null]]
2014-03-28 15:10:46,024 INFO [geoserver.wms] - 
Request: getServiceInfo
2014-03-28 15:10:46,027 DEBUG [geoserver.ows] - Getting layers and styles from LAYERS and STYLES
2014-03-28 15:10:46,028 DEBUG [geotools.styling] - number of fts set 1
2014-03-28 15:10:46,029 DEBUG [geoserver.ows] - establishing point style for BASE:StateCapitals
2014-03-28 15:10:46,038 DEBUG [config.impl] - Could not locate service of type interface org.geoserver.wms.WMSInfo in workspace WorkspaceInfoImpl[BASE], available services were [WCSInfoImpl[WCS], WFSInfoImpl[WFS], WMSInfoImpl[WMS], WPSInfoImpl[null]]
2014-03-28 15:10:46,038 DEBUG [config.impl] - Could not locate service of type interface org.geoserver.wms.WMSInfo in workspace WorkspaceInfoImpl[BASE], available services were [WCSInfoImpl[WCS], WFSInfoImpl[WFS], WMSInfoImpl[WMS], WPSInfoImpl[null]]
2014-03-28 15:10:46,039 DEBUG [geoserver.wms] - setting up map
2014-03-28 15:10:46,042 DEBUG [config.impl] - Could not locate service of type interface org.geoserver.wms.WMSInfo in workspace WorkspaceInfoImpl[BASE], available services were [WCSInfoImpl[WCS], WFSInfoImpl[WFS], WMSInfoImpl[WMS], WPSInfoImpl[null]]
2014-03-28 15:10:46,043 DEBUG [wms.map] - setting up 1200x700 image
2014-03-28 15:10:46,043 DEBUG [config.impl] - Could not locate service of type interface org.geoserver.wms.WMSInfo in workspace WorkspaceInfoImpl[BASE], available services were [WCSInfoImpl[WCS], WFSInfoImpl[WFS], WMSInfoImpl[WMS], WPSInfoImpl[null]]
2014-03-28 15:10:46,057 DEBUG [geoserver.security] - preAuthenticatedPrincipal = admin, trying to authenticate
2014-03-28 15:10:46,058 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/zoombar.png]
2014-03-28 15:10:46,058 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/zoombar.png]
2014-03-28 15:10:46,058 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/zoombar.png]
2014-03-28 15:10:46,058 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/zoombar.png]
2014-03-28 15:10:46,059 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/zoombar.png]
2014-03-28 15:10:46,059 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/zoombar.png]
2014-03-28 15:10:46,059 TRACE [ows.OWSHandlerMapping] - No handler mapping found for [/openlayers/img/zoombar.png]
2014-03-28 15:10:46,229 DEBUG [config.impl] - Could not locate service of type interface org.geoserver.wms.WMSInfo in workspace WorkspaceInfoImpl[BASE], available services were [WCSInfoImpl[WCS], WFSInfoImpl[WFS], WMSInfoImpl[WMS], WPSInfoImpl[null]]
2014-03-28 15:10:46,229 DEBUG [config.impl] - Could not locate service of type interface org.geoserver.wms.WMSInfo in workspace WorkspaceInfoImpl[BASE], available services were [WCSInfoImpl[WCS], WFSInfoImpl[WFS], WMSInfoImpl[WMS], WPSInfoImpl[null]]
2014-03-28 15:10:46,233 DEBUG [config.impl] - Could not locate service of type interface org.geoserver.wms.WMSInfo in workspace WorkspaceInfoImpl[BASE], available services were [WCSInfoImpl[WCS], WFSInfoImpl[WFS], WMSInfoImpl[WMS], WPSInfoImpl[null]]
2014-03-28 15:10:46,281 DEBUG [geotools.rendering] - Computed scale denominator: 1.397705660071794E8
2014-03-28 15:10:46,302 DEBUG [geotools.rendering] - Processing 1 stylers for http://www.issinc.com/base:StateCapitals
2014-03-28 15:10:46,302 DEBUG [geotools.rendering] - creating rules for scale denominator - 139,770,566.007
2014-03-28 15:10:46,309 DEBUG [geotools.styling] - creating defaultMark
2014-03-28 15:10:46,310 DEBUG [geotools.styling] - creating defaultMark
2014-03-28 15:10:46,310 TRACE [geotools.styling] - ENTRY
2014-03-28 15:10:46,310 DEBUG [geotools.styling] - creating defaultMark
2014-03-28 15:10:46,317 DEBUG [geotools.rendering] - Expanding rendering area by 4 pixels to consider stroke width
2014-03-28 15:10:46,372 DEBUG [geotools.rendering] - Querying layer http://www.issinc.com/base:StateCapitals with bbox: ReferencedEnvelope[-212.34375 : 212.34375, -124.453125 : 124.453125]
2014-03-28 15:10:46,471 DEBUG [geotools.data] - Recasting feature type to subtype by using a ReTypeFeatureReader

The console logger has the following:
DEBUG [http-nio-8443-exec-2-SendThread(kepler4.issinc.com:2181)] (ClientCnxn.java:758) - Got ping response for sessionid: 0x444f5f589321f59 after 0ms
DEBUG [http-nio-8443-exec-2-SendThread(kepler4.issinc.com:2181)] (ClientCnxn.java:758) - Got ping response for sessionid: 0x444f5f589321f59 after 0ms
DEBUG [http-nio-8443-exec-2-SendThread(kepler4.issinc.com:2181)] (ClientCnxn.java:758) - Got ping response for sessionid: 0x444f5f589321f59 after 2ms
DEBUG [http-nio-8443-exec-2-SendThread(kepler4.issinc.com:2181)] (ClientCnxn.java:758) - Got ping response for sessionid: 0x444f5f589321f59 after 1ms
DEBUG [http-nio-8443-exec-2-SendThread(kepler4.issinc.com:2181)] (ClientCnxn.java:758) - Got ping response for sessionid: 0x444f5f589321f59 after 1ms
DEBUG [http-nio-8443-exec-2-SendThread(kepler4.issinc.com:2181)] (ClientCnxn.java:758) - Got ping response for sessionid: 0x444f5f589321f59 after 2ms
DEBUG [http-nio-8443-exec-2-SendThread(kepler4.issinc.com:2181)] (ClientCnxn.java:758) - Got ping response for sessionid: 0x444f5f589321f59 after 1ms
DEBUG [http-nio-8443-exec-2-SendThread(kepler4.issinc.com:2181)] (ClientCnxn.java:758) - Got ping response for sessionid: 0x444f5f589321f59 after 1ms
DEBUG [http-nio-8443-exec-2-SendThread(kepler4.issinc.com:2181)] (ClientCnxn.java:758) - Got ping response for sessionid: 0x444f5f589321f59 after 1ms
 INFO [http-nio-8443-exec-2-SendThread(kepler4.issinc.com:2181)] (ClientCnxn.java:1159) - Unable to read additional data from server sessionid 0x444f5f589321f59, likely server has closed socket, closing socket connection and attempting reconnect
 INFO [http-nio-8443-exec-2-SendThread(kepler4.issinc.com:2181)] (ClientCnxn.java:1041) - Opening socket connection to server kepler4.issinc.com/172.22.60.24:2181

Zooming in on the openlayers preview page to scale=1:9M, I get the image returned and the following log file entries

2014-03-28 15:18:38,036 DEBUG [geoserver.wms] - setting up map
2014-03-28 15:18:38,077 DEBUG [config.impl] - Could not locate service of type interface org.geoserver.wms.WMSInfo in workspace WorkspaceInfoImpl[BASE], available services were [WCSInfoImpl[WCS], WFSInfoImpl[WFS], WMSInfoImpl[WMS], WPSInfoImpl[null]]
2014-03-28 15:18:38,077 DEBUG [wms.map] - setting up 1200x700 image
2014-03-28 15:18:38,077 DEBUG [config.impl] - Could not locate service of type interface org.geoserver.wms.WMSInfo in workspace WorkspaceInfoImpl[BASE], available services were [WCSInfoImpl[WCS], WFSInfoImpl[WFS], WMSInfoImpl[WMS], WPSInfoImpl[null]]
2014-03-28 15:18:38,209 DEBUG [config.impl] - Could not locate service of type interface org.geoserver.wms.WMSInfo in workspace WorkspaceInfoImpl[BASE], available services were [WCSInfoImpl[WCS], WFSInfoImpl[WFS], WMSInfoImpl[WMS], WPSInfoImpl[null]]
2014-03-28 15:18:38,210 DEBUG [config.impl] - Could not locate service of type interface org.geoserver.wms.WMSInfo in workspace WorkspaceInfoImpl[BASE], available services were [WCSInfoImpl[WCS], WFSInfoImpl[WFS], WMSInfoImpl[WMS], WPSInfoImpl[null]]
2014-03-28 15:18:38,214 DEBUG [config.impl] - Could not locate service of type interface org.geoserver.wms.WMSInfo in workspace WorkspaceInfoImpl[BASE], available services were [WCSInfoImpl[WCS], WFSInfoImpl[WFS], WMSInfoImpl[WMS], WPSInfoImpl[null]]
2014-03-28 15:18:38,249 DEBUG [geotools.rendering] - Computed scale denominator: 8735660.375448713
2014-03-28 15:18:38,269 DEBUG [geotools.rendering] - Processing 1 stylers for http://www.issinc.com/base:StateCapitals
2014-03-28 15:18:38,269 DEBUG [geotools.rendering] - creating rules for scale denominator - 8,735,660.375
2014-03-28 15:18:38,276 DEBUG [geotools.styling] - creating defaultMark
2014-03-28 15:18:38,277 DEBUG [geotools.styling] - creating defaultMark
2014-03-28 15:18:38,277 TRACE [geotools.styling] - ENTRY
2014-03-28 15:18:38,277 DEBUG [geotools.styling] - creating defaultMark
2014-03-28 15:18:38,283 DEBUG [geotools.rendering] - Expanding rendering area by 4 pixels to consider stroke width
2014-03-28 15:18:38,302 DEBUG [referencing.factory] - The EPSG factory org.geotools.referencing.factory.epsg.ThreadedHsqlEpsgFactory will not timeout
2014-03-28 15:18:38,308 DEBUG [referencing.factory] - The EPSG factory org.geotools.referencing.factory.epsg.DefaultFactory will not timeout
2014-03-28 15:18:38,311 INFO [referencing.factory] - C:\cep\apache-tomcat-7.0.25\webapps\geoserver\data\user_projections\epsg_operations.properties was not found, using the default set of coordinate operation overrides (normally empty)
2014-03-28 15:18:38,313 INFO [referencing.factory] - Using "file:/C:/cep/apache-tomcat-7.0.25/webapps/geoserver/WEB-INF/lib/main-2.4.4.jar!/org/vfny/geoserver/crs/epsg_operations.properties" as EPSG factory.
2014-03-28 15:18:38,349 DEBUG [geotools.rendering] - Querying layer http://www.issinc.com/base:StateCapitals with bbox: ReferencedEnvelope[-59.501953125 : -32.958984375, -41.0009765625 : -25.4443359375]
2014-03-28 15:18:38,432 DEBUG [geotools.data] - Recasting feature type to subtype by using a ReTypeFeatureReader
2014-03-28 15:18:41,386 DEBUG [geotools.rendering] - trying to render symbol square
2014-03-28 15:18:41,387 DEBUG [geotools.rendering] - rendering mark @ PointRenderer square
2014-03-28 15:18:41,401 DEBUG [geotools.rendering] - fetching mark of name square
2014-03-28 15:18:41,401 DEBUG [geotools.rendering] - returning square
2014-03-28 15:18:41,429 DEBUG [geotools.rendering] - Style cache hit ratio: 0.9090909090909091 , hits 10, requests 11
2014-03-28 15:18:41,430 INFO [geoserver.wms] - 
Request: getMap
	Filter = [Filter.INCLUDE]
	Time = []
	Buffer = 0
	Format = image/png
	Filters = [Filter.INCLUDE]
	Transparent = false
	Exceptions = SE_XML
	Tiled = false
	Palette = null
	Height = 700
	Width = 1200
	SRS = EPSG:4326
	Styles = [StyleImpl[ name=point]]
	BgColor = java.awt.Color[r=255,g=255,b=255]
	FeatureVersion = null
	Crs = GEOGCS["WGS 84", 
  DATUM["World Geodetic System 1984", 
    SPHEROID["WGS 84", 6378137.0, 298.257223563, AUTHORITY["EPSG","7030"]], 
    AUTHORITY["EPSG","6326"]], 
  PRIMEM["Greenwich", 0.0, AUTHORITY["EPSG","8901"]], 
  UNIT["degree", 0.017453292519943295], 
  AXIS["Geodetic longitude", EAST], 
  AXIS["Geodetic latitude", NORTH], 
  AUTHORITY["EPSG","4326"]]
	Bbox = ReferencedEnvelope[-59.4140625 : -33.046875, -40.9130859375 : -25.5322265625]
	Env = {}
	Angle = 0.0
	CQLFilter = [Filter.INCLUDE]
	Elevation = []
	FeatureId = null
	SldBody = null
	Sld = null
	RemoteOwsType = null
	RemoteOwsURL = null
	FormatOptions = {}
	StartIndex = null
	ViewParams = null
	Layers = [org.geoserver.wms.MapLayerInfo@57f54b6e]
	ValidateSchema = false
	SldVersion = null
	TilesOrigin = null
	MaxFeatures = null
	Version = 1.1.1
	Request = GetMap
	RequestCharset = UTF-8
	BaseUrl = https://iss203371:8443/geoserver/
	Get = true
	RawKvp = {BBOX=-59.4140625,-40.9130859375,-33.046875,-25.5322265625, VERSION=1.1.1, FORMAT=image/png, SERVICE=WMS, HEIGHT=700, LAYERS=BASE:StateCapitals, REQUEST=GetMap, STYLES=, SRS=EPSG:4326, WIDTH=1200}
2014-03-28 15:18:41,483 DEBUG [wms.map] - Writing png image ...
2014-03-28 15:18:41,484 DEBUG [config.impl] - Could not locate service of type interface org.geoserver.wms.WMSInfo in workspace WorkspaceInfoImpl[BASE], available services were [WCSInfoImpl[WCS], WFSInfoImpl[WFS], WMSInfoImpl[WMS], WPSInfoImpl[null]]
2014-03-28 15:18:41,504 INFO [geotools.image] - Warp/affine reduction enabled: true
2014-03-28 15:18:41,504 DEBUG [geotools.image] - Encoded input image for png writer
2014-03-28 15:18:41,504 DEBUG [geotools.image] - Getting a writer
2014-03-28 15:18:41,504 DEBUG [geotools.image] - Unable to use Native ImageIO PNG writer.
2014-03-28 15:18:41,507 DEBUG [geotools.image] - Using ImageIO Writer with SPI: com.sun.imageio.plugins.png.PNGImageWriterSpi
2014-03-28 15:18:41,507 DEBUG [geotools.image] - Setting write parameters for this writer
2014-03-28 15:18:41,508 DEBUG [geotools.image] - Writer is NOT native
2014-03-28 15:18:41,508 DEBUG [geotools.image] - About to write png image
2014-03-28 15:18:41,900 DEBUG [wms.map] - Writing png image ... done!


This returns 11 of the 20 items stored in accumulo

Thanks,

Chris Snider
Senior Software Engineer



-----Original Message-----
From: geomesa-users-bounces@xxxxxxxxxxxxxxxx [mailto:geomesa-users-bounces@xxxxxxxxxxxxxxxx] On Behalf Of Chris Eichelberger
Sent: Friday, March 28, 2014 2:29 PM
To: geomesa-users@xxxxxxxxxxxxxxxx
Subject: Re: [geomesa-users] WMS 1.1.0 getMap call Hangs

Chris,

Does your GeoServer log contain any entries for the time when the WMS query is running (or timing out)?

Thanks!

Sincerely,
  -- Chris

_______________

Chris Eichelberger,
Systems Engineer
Commonwealth Computer Research, Inc.
434-284-9422 (work)




> 
> _______________________________________________
> geomesa-users mailing list
> geomesa-users@xxxxxxxxxxxxxxxx
> http://www.locationtech.org/mailman/listinfo/geomesa-users

_______________________________________________
geomesa-users mailing list
geomesa-users@xxxxxxxxxxxxxxxx
http://www.locationtech.org/mailman/listinfo/geomesa-users
Follow-Ups:
- Re: [geomesa-users] WMS 1.1.0 getMap call Hangs
  - From: Chris Eichelberger
References:
- [geomesa-users] WMS 1.1.0 getMap call Hangs
  - From: Chris Snider
- Re: [geomesa-users] WMS 1.1.0 getMap call Hangs
  - From: Chris Eichelberger
Prev by Date: Re: [geomesa-users] WMS 1.1.0 getMap call Hangs
Next by Date: Re: [geomesa-users] WMS 1.1.0 getMap call Hangs
Previous by thread: Re: [geomesa-users] WMS 1.1.0 getMap call Hangs
Next by thread: Re: [geomesa-users] WMS 1.1.0 getMap call Hangs
Index(es):
- Date
- Thread
Breadcrumbs
