[Geoserver-users] GeoServer 2.1 image generation taking long time

Hi,
I've spatial coverage data in an Oracle Spatial Database table. I've exposed the table as a layer. When I'm invoke the layer as a PNG image or using Open Layer application(tiled request) within Geo Server, I see that it is taking quite a long time to render the images. From the log (log profile = GEOSERVER_DEVELOPER_LOGGING.properties), I see that GeoServer has taken ~3.5 secs (between 11:55:02,871 - 11:55:06,434 AM) to 'set up image' and process the getMap request. I would like to know what exactly is happening within this 3.5 secs. Is this time being taken by the SQL query to determine the polygons within the bounding box supplied? The overall time required by the request to be processed and displayed on the client machine is around 9 secs which is quite slow. Any help would be greatly appreciated. Below is the log.

=========================== GeoServer Log ===================

2014-07-10 11:55:02,777 DEBUG [org.geoserver.filters] - filtering http://ip:80/geoserver/icc_sa/wms
2014-07-10 11:55:02,777 INFO [org.geoserver.wms] -
Request: getServiceInfo
2014-07-10 11:55:02,777 DEBUG [org.geoserver.ows] - Getting layers and styles from LAYERS and STYLES
2014-07-10 11:55:02,777 DEBUG [org.geoserver.ows] - establishing Style_ICC_SA_DMA style for icc_sa:ICC_SA_LAYER_DMA_COVERAGE
2014-07-10 11:55:02,777 DEBUG [org.geoserver.wms] - Tiled request detected, activating on the fly meta tiler
2014-07-10 11:55:02,777 DEBUG [org.geoserver.wms] - setting up map
2014-07-10 11:55:02,777 DEBUG [org.geoserver.filters] - filtering http://ip:80/geoserver/icc_sa/wms
2014-07-10 11:55:02,793 INFO [org.geoserver.wms] -
Request: getServiceInfo
2014-07-10 11:55:02,793 DEBUG [org.geoserver.ows] - Getting layers and styles from LAYERS and STYLES
2014-07-10 11:55:02,793 DEBUG [org.geoserver.ows] - establishing Style_ICC_SA_DMA style for icc_sa:ICC_SA_LAYER_DMA_COVERAGE
2014-07-10 11:55:02,793 DEBUG [org.geoserver.wms] - Tiled request detected, activating on the fly meta tiler
2014-07-10 11:55:02,793 DEBUG [org.geoserver.wms] - setting up map
2014-07-10 11:55:02,777 DEBUG [org.geoserver.filters] - filtering http://ip:80/geoserver/icc_sa/wms
2014-07-10 11:55:02,793 INFO [org.geoserver.wms] -
Request: getServiceInfo
2014-07-10 11:55:02,809 DEBUG [org.geoserver.ows] - Getting layers and styles from LAYERS and STYLES
2014-07-10 11:55:02,809 DEBUG [org.geoserver.ows] - establishing Style_ICC_SA_DMA style for icc_sa:ICC_SA_LAYER_DMA_COVERAGE
2014-07-10 11:55:02,809 DEBUG [org.geoserver.wms] - Tiled request detected, activating on the fly meta tiler
2014-07-10 11:55:02,809 DEBUG [org.geoserver.wms] - setting up map
2014-07-10 11:55:02,777 DEBUG [org.geoserver.filters] - filtering http://ip:80/geoserver/icc_sa/wms
2014-07-10 11:55:02,809 INFO [org.geoserver.wms] -
Request: getServiceInfo
2014-07-10 11:55:02,809 DEBUG [org.geoserver.ows] - Getting layers and styles from LAYERS and STYLES
2014-07-10 11:55:02,809 DEBUG [org.geoserver.ows] - establishing Style_ICC_SA_DMA style for icc_sa:ICC_SA_LAYER_DMA_COVERAGE
2014-07-10 11:55:02,809 DEBUG [org.geoserver.wms] - Tiled request detected, activating on the fly meta tiler
2014-07-10 11:55:02,824 DEBUG [org.geoserver.wms] - setting up map
2014-07-10 11:55:02,777 DEBUG [org.geoserver.filters] - filtering http://ip:80/geoserver/icc_sa/wms
2014-07-10 11:55:02,824 INFO [org.geoserver.wms] -
Request: getServiceInfo
2014-07-10 11:55:02,824 DEBUG [org.geoserver.ows] - Getting layers and styles from LAYERS and STYLES
2014-07-10 11:55:02,824 DEBUG [org.geoserver.ows] - establishing Style_ICC_SA_DMA style for icc_sa:ICC_SA_LAYER_DMA_COVERAGE
2014-07-10 11:55:02,824 DEBUG [org.geoserver.wms] - Tiled request detected, activating on the fly meta tiler
2014-07-10 11:55:02,824 DEBUG [org.geoserver.wms] - setting up map
2014-07-10 11:55:02,840 DEBUG [org.geoserver.wms.map] - Looked for meta tile 15, 3in cache: miss
2014-07-10 11:55:02,840 DEBUG [org.geoserver.wms.map] - Building meta tile 15, 3
2014-07-10 11:55:02,840 DEBUG [org.geoserver.wms.map] - setting up 768x768 image
2014-07-10 11:55:02,871 DEBUG [org.geoserver.wms.map] - Looked for meta tile 15, 0in cache: miss
2014-07-10 11:55:02,871 DEBUG [org.geoserver.wms.map] - Building meta tile 15, 0
2014-07-10 11:55:02,871 DEBUG [org.geoserver.wms.map] - setting up 768x768 image
2014-07-10 11:55:06,434 INFO [org.geoserver.wms] -
Request: getMap
  Bbox = ReferencedEnvelope[-4.709375 : -4.478, 55.01775 : 55.249125]
  BgColor = java.awt.Color[r=255,g=255,b=255]
  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"]]
  SRS = EPSG:4326
  Exceptions = application/vnd.ogc.se_inimage
  Format = image/png
  FormatOptions = {}
  Env = {}
  ViewParams = {}
  Height = 256
  Layers = [org.geoserver.wms.MapLayerInfo@anonymised.com]
  Styles = [StyleImpl[ name=Style_ICC_SA_DMA]]
  Sld = null
  SldBody = null
  SldVersion = null
  ValidateSchema = false
  Filters = null
  Filter = null
  CQLFilter = null
  FeatureId = null
  Transparent = false
  Tiled = true
  TilesOrigin = Point2D.Double[-8.18, 54.555]
  Buffer = 0
  Palette = null
  Width = 256
  KMScore = 40
  KMattr = true
  Legend = false
  Time = null
  Elevation = NaN
  FeatureVersion = null
  RemoteOwsType = null
  RemoteOwsURL = null
  MaxFeatures = null
  StartIndex = null
  Angle = 0.0
  Get = true
  Request = GetMap
  Version = 1.1.1
  BaseUrl = http://ip:80/geoserver/
  RawKvp = {BBOX=-4.709375,55.01775,-4.478,55.249125, TILED=true, SERVICE=WMS, HEIGHT=256, REQUEST=GetMap, STYLES=, TILESORIGIN=-8.18,54.555, WIDTH=256, EXCEPTIONS=application/vnd.ogc.se_inimage, VERSION=1.1.1, FORMAT=image/png, LAYERS=icc_sa:ICC_SA_LAYER_DMA_COVERAGE, SRS=EPSG:4326}
  RequestCharset = UTF-8
2014-07-10 11:55:06,449 DEBUG [org.geoserver.wms.map] - Looked for meta tile 15, 0in cache: hit!
2014-07-10 11:55:06,449 DEBUG [org.geoserver.wms.map] - Writing png image ...
2014-07-10 11:55:06,449 INFO [org.geoserver.wms] -
Request: getMap
  Bbox = ReferencedEnvelope[-4.478 : -4.246625, 55.01775 : 55.249125]
  BgColor = java.awt.Color[r=255,g=255,b=255]
  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"]]
  SRS = EPSG:4326
  Exceptions = application/vnd.ogc.se_inimage
  Format = image/png
  FormatOptions = {}
  Env = {}
  ViewParams = {}
  Height = 256
  Layers = [org.geoserver.wms.MapLayerInfo@anonymised.com]
  Styles = [StyleImpl[ name=Style_ICC_SA_DMA]]
  Sld = null
  SldBody = null
  SldVersion = null
  ValidateSchema = false
  Filters = null
  Filter = null
  CQLFilter = null
  FeatureId = null
  Transparent = false
  Tiled = true
  TilesOrigin = Point2D.Double[-8.18, 54.555]
  Buffer = 0
  Palette = null
  Width = 256
  KMScore = 40
  KMattr = true
  Legend = false
  Time = null
  Elevation = NaN
  FeatureVersion = null
  RemoteOwsType = null
  RemoteOwsURL = null
  MaxFeatures = null
  StartIndex = null
  Angle = 0.0
  Get = true
  Request = GetMap
  Version = 1.1.1
  BaseUrl = http://ip:80/geoserver/
  RawKvp = {BBOX=-4.478,55.01775,-4.246625,55.249125, TILED=true, SERVICE=WMS, HEIGHT=256, REQUEST=GetMap, STYLES=, TILESORIGIN=-8.18,54.555, WIDTH=256, EXCEPTIONS=application/vnd.ogc.se_inimage, VERSION=1.1.1, FORMAT=image/png, LAYERS=icc_sa:ICC_SA_LAYER_DMA_COVERAGE, SRS=EPSG:4326}
  RequestCharset = UTF-8
2014-07-10 11:55:06,449 DEBUG [org.geoserver.wms.map] - Writing png image ...
2014-07-10 11:55:06,465 DEBUG [org.geoserver.wms.map] - Writing png image ... done!
2014-07-10 11:55:06,465 DEBUG [org.geoserver.wms.map] - Writing png image ... done!
2014-07-10 11:55:06,465 DEBUG [org.geoserver.filters] - filtering http://ip:80/geoserver/icc_sa/wms
2014-07-10 11:55:06,465 INFO [org.geoserver.wms] -
Request: getServiceInfo
2014-07-10 11:55:06,481 DEBUG [org.geoserver.ows] - Getting layers and styles from LAYERS and STYLES
2014-07-10 11:55:06,481 DEBUG [org.geoserver.filters] - filtering http://ip:80/geoserver/icc_sa/wms
2014-07-10 11:55:06,481 DEBUG [org.geoserver.ows] - establishing Style_ICC_SA_DMA style for icc_sa:ICC_SA_LAYER_DMA_COVERAGE
2014-07-10 11:55:06,481 INFO [org.geoserver.wms] -
Request: getServiceInfo
2014-07-10 11:55:06,481 DEBUG [org.geoserver.wms] - Tiled request detected, activating on the fly meta tiler
2014-07-10 11:55:06,481 DEBUG [org.geoserver.ows] - Getting layers and styles from LAYERS and STYLES
2014-07-10 11:55:06,481 DEBUG [org.geoserver.wms] - setting up map
2014-07-10 11:55:06,481 DEBUG [org.geoserver.ows] - establishing Style_ICC_SA_DMA style for icc_sa:ICC_SA_LAYER_DMA_COVERAGE
2014-07-10 11:55:06,481 DEBUG [org.geoserver.wms] - Tiled request detected, activating on the fly meta tiler
2014-07-10 11:55:06,481 DEBUG [org.geoserver.wms] - setting up map
2014-07-10 11:55:06,543 DEBUG [org.geoserver.wms.map] - Looked for meta tile 15, 0in cache: hit!
2014-07-10 11:55:06,559 INFO [org.geoserver.wms] -
Request: getMap
  Bbox = ReferencedEnvelope[-4.246625 : -4.01525, 55.01775 : 55.249125]
  BgColor = java.awt.Color[r=255,g=255,b=255]
  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"]]
  SRS = EPSG:4326
  Exceptions = application/vnd.ogc.se_inimage
  Format = image/png
  FormatOptions = {}
  Env = {}
  ViewParams = {}
  Height = 256
  Layers = [org.geoserver.wms.MapLayerInfo@anonymised.com]
  Styles = [StyleImpl[ name=Style_ICC_SA_DMA]]
  Sld = null
  SldBody = null
  SldVersion = null
  ValidateSchema = false
  Filters = null
  Filter = null
  CQLFilter = null
  FeatureId = null
  Transparent = false
  Tiled = true
  TilesOrigin = Point2D.Double[-8.18, 54.555]
  Buffer = 0
  Palette = null
  Width = 256
  KMScore = 40
  KMattr = true
  Legend = false
  Time = null
  Elevation = NaN
  FeatureVersion = null
  RemoteOwsType = null
  RemoteOwsURL = null
  MaxFeatures = null
  StartIndex = null
  Angle = 0.0
  Get = true
  Request = GetMap
  Version = 1.1.1
  BaseUrl = http://ip:80/geoserver/
  RawKvp = {BBOX=-4.246625,55.01775,-4.01525,55.249125, TILED=true, SERVICE=WMS, HEIGHT=256, REQUEST=GetMap, STYLES=, TILESORIGIN=-8.18,54.555, WIDTH=256, EXCEPTIONS=application/vnd.ogc.se_inimage, VERSION=1.1.1, FORMAT=image/png, LAYERS=icc_sa:ICC_SA_LAYER_DMA_COVERAGE, SRS=EPSG:4326}
  RequestCharset = UTF-8
2014-07-10 11:55:06,559 DEBUG [org.geoserver.wms.map] - Writing png image ...
2014-07-10 11:55:06,590 DEBUG [org.geoserver.wms.map] - Writing png image ... done!
2014-07-10 11:55:06,590 DEBUG [org.geoserver.filters] - filtering http://ip:80/geoserver/icc_sa/wms
2014-07-10 11:55:06,590 INFO [org.geoserver.wms] -
Request: getServiceInfo
2014-07-10 11:55:06,590 DEBUG [org.geoserver.ows] - Getting layers and styles from LAYERS and STYLES
2014-07-10 11:55:06,606 DEBUG [org.geoserver.ows] - establishing Style_ICC_SA_DMA style for icc_sa:ICC_SA_LAYER_DMA_COVERAGE
2014-07-10 11:55:06,606 DEBUG [org.geoserver.wms] - Tiled request detected, activating on the fly meta tiler
2014-07-10 11:55:06,606 DEBUG [org.geoserver.wms] - setting up map
2014-07-10 11:55:06,637 INFO [org.geoserver.wms] -
Request: getMap
  Bbox = ReferencedEnvelope[-4.478 : -4.246625, 55.249125 : 55.4805]
  BgColor = java.awt.Color[r=255,g=255,b=255]
  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"]]
  SRS = EPSG:4326
  Exceptions = application/vnd.ogc.se_inimage
  Format = image/png
  FormatOptions = {}
  Env = {}
  ViewParams = {}
  Height = 256
  Layers = [org.geoserver.wms.MapLayerInfo@anonymised.com]
  Styles = [StyleImpl[ name=Style_ICC_SA_DMA]]
  Sld = null
  SldBody = null
  SldVersion = null
  ValidateSchema = false
  Filters = null
  Filter = null
  CQLFilter = null
  FeatureId = null
  Transparent = false
  Tiled = true
  TilesOrigin = Point2D.Double[-8.18, 54.555]
  Buffer = 0
  Palette = null
  Width = 256
  KMScore = 40
  KMattr = true
  Legend = false
  Time = null
  Elevation = NaN
  FeatureVersion = null
  RemoteOwsType = null
  RemoteOwsURL = null
  MaxFeatures = null
  StartIndex = null
  Angle = 0.0
  Get = true
  Request = GetMap
  Version = 1.1.1
  BaseUrl = http://ip:80/geoserver/
  RawKvp = {BBOX=-4.478,55.249125,-4.246625,55.4805, TILED=true, SERVICE=WMS, HEIGHT=256, REQUEST=GetMap, STYLES=, TILESORIGIN=-8.18,54.555, WIDTH=256, EXCEPTIONS=application/vnd.ogc.se_inimage, VERSION=1.1.1, FORMAT=image/png, LAYERS=icc_sa:ICC_SA_LAYER_DMA_COVERAGE, SRS=EPSG:4326}
  RequestCharset = UTF-8
2014-07-10 11:55:06,637 DEBUG [org.geoserver.wms.map] - Writing png image ...
2014-07-10 11:55:06,652 DEBUG [org.geoserver.wms.map] - Looked for meta tile 15, 3in cache: hit!
2014-07-10 11:55:06,652 INFO [org.geoserver.wms] -
Request: getMap
  Bbox = ReferencedEnvelope[-4.246625 : -4.01525, 55.4805 : 55.711875]
  BgColor = java.awt.Color[r=255,g=255,b=255]
  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"]]
  SRS = EPSG:4326
  Exceptions = application/vnd.ogc.se_inimage
  Format = image/png
  FormatOptions = {}
  Env = {}
  ViewParams = {}
  Height = 256
  Layers = [org.geoserver.wms.MapLayerInfo@anonymised.com]
  Styles = [StyleImpl[ name=Style_ICC_SA_DMA]]
  Sld = null
  SldBody = null
  SldVersion = null
  ValidateSchema = false
  Filters = null
  Filter = null
  CQLFilter = null
  FeatureId = null
  Transparent = false
  Tiled = true
  TilesOrigin = Point2D.Double[-8.18, 54.555]
  Buffer = 0
  Palette = null
  Width = 256
  KMScore = 40
  KMattr = true
  Legend = false
  Time = null
  Elevation = NaN
  FeatureVersion = null
  RemoteOwsType = null
  RemoteOwsURL = null
  MaxFeatures = null
  StartIndex = null
  Angle = 0.0
  Get = true
  Request = GetMap
  Version = 1.1.1
  BaseUrl = http://ip:80/geoserver/
  RawKvp = {BBOX=-4.246625,55.4805,-4.01525,55.711875, TILED=true, SERVICE=WMS, HEIGHT=256, REQUEST=GetMap, STYLES=, TILESORIGIN=-8.18,54.555, WIDTH=256, EXCEPTIONS=application/vnd.ogc.se_inimage, VERSION=1.1.1, FORMAT=image/png, LAYERS=icc_sa:ICC_SA_LAYER_DMA_COVERAGE, SRS=EPSG:4326}
  RequestCharset = UTF-8
2014-07-10 11:55:06,652 DEBUG [org.geoserver.wms.map] - Writing png image ...
2014-07-10 11:55:06,668 DEBUG [org.geoserver.wms.map] - Looked for meta tile 15, 3in cache: hit!
2014-07-10 11:55:06,668 INFO [org.geoserver.wms] -
Request: getMap
  Bbox = ReferencedEnvelope[-4.246625 : -4.01525, 55.249125 : 55.4805]
  BgColor = java.awt.Color[r=255,g=255,b=255]
  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"]]
  SRS = EPSG:4326
  Exceptions = application/vnd.ogc.se_inimage
  Format = image/png
  FormatOptions = {}
  Env = {}
  ViewParams = {}
  Height = 256
  Layers = [org.geoserver.wms.MapLayerInfo@anonymised.com]
  Styles = [StyleImpl[ name=Style_ICC_SA_DMA]]
  Sld = null
  SldBody = null
  SldVersion = null
  ValidateSchema = false
  Filters = null
  Filter = null
  CQLFilter = null
  FeatureId = null
  Transparent = false
  Tiled = true
  TilesOrigin = Point2D.Double[-8.18, 54.555]
  Buffer = 0
  Palette = null
  Width = 256
  KMScore = 40
  KMattr = true
  Legend = false
  Time = null
  Elevation = NaN
  FeatureVersion = null
  RemoteOwsType = null
  RemoteOwsURL = null
  MaxFeatures = null
  StartIndex = null
  Angle = 0.0
  Get = true
  Request = GetMap
  Version = 1.1.1
  BaseUrl = http://ip:80/geoserver/
  RawKvp = {BBOX=-4.246625,55.249125,-4.01525,55.4805, TILED=true, SERVICE=WMS, HEIGHT=256, REQUEST=GetMap, STYLES=, TILESORIGIN=-8.18,54.555, WIDTH=256, EXCEPTIONS=application/vnd.ogc.se_inimage, VERSION=1.1.1, FORMAT=image/png, LAYERS=icc_sa:ICC_SA_LAYER_DMA_COVERAGE, SRS=EPSG:4326}
  RequestCharset = UTF-8
2014-07-10 11:55:06,668 DEBUG [org.geoserver.wms.map] - Looked for meta tile 15, 3in cache: hit!
2014-07-10 11:55:06,668 INFO [org.geoserver.wms] -
Request: getMap
  Bbox = ReferencedEnvelope[-4.709375 : -4.478, 55.249125 : 55.4805]
  BgColor = java.awt.Color[r=255,g=255,b=255]
  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"]]
  SRS = EPSG:4326
  Exceptions = application/vnd.ogc.se_inimage
  Format = image/png
  FormatOptions = {}
  Env = {}
  ViewParams = {}
  Height = 256
  Layers = [org.geoserver.wms.MapLayerInfo@anonymised.com]
  Styles = [StyleImpl[ name=Style_ICC_SA_DMA]]
  Sld = null
  SldBody = null
  SldVersion = null
  ValidateSchema = false
  Filters = null
  Filter = null
  CQLFilter = null
  FeatureId = null
  Transparent = false
  Tiled = true
  TilesOrigin = Point2D.Double[-8.18, 54.555]
  Buffer = 0
  Palette = null
  Width = 256
  KMScore = 40
  KMattr = true
  Legend = false
  Time = null
  Elevation = NaN
  FeatureVersion = null
  RemoteOwsType = null
  RemoteOwsURL = null
  MaxFeatures = null
  StartIndex = null
  Angle = 0.0
  Get = true
  Request = GetMap
  Version = 1.1.1
  BaseUrl = http://ip:80/geoserver/
  RawKvp = {BBOX=-4.709375,55.249125,-4.478,55.4805, TILED=true, SERVICE=WMS, HEIGHT=256, REQUEST=GetMap, STYLES=, TILESORIGIN=-8.18,54.555, WIDTH=256, EXCEPTIONS=application/vnd.ogc.se_inimage, VERSION=1.1.1, FORMAT=image/png, LAYERS=icc_sa:ICC_SA_LAYER_DMA_COVERAGE, SRS=EPSG:4326}
  RequestCharset = UTF-8
2014-07-10 11:55:06,668 DEBUG [org.geoserver.wms.map] - Looked for meta tile 15, 3in cache: hit!
2014-07-10 11:55:06,668 DEBUG [org.geoserver.wms.map] - Writing png image ...
2014-07-10 11:55:06,668 DEBUG [org.geoserver.wms.map] - Writing png image ...
2014-07-10 11:55:06,668 INFO [org.geoserver.wms] -
Request: getMap
  Bbox = ReferencedEnvelope[-4.478 : -4.246625, 55.4805 : 55.711875]
  BgColor = java.awt.Color[r=255,g=255,b=255]
  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"]]
  SRS = EPSG:4326
  Exceptions = application/vnd.ogc.se_inimage
  Format = image/png
  FormatOptions = {}
  Env = {}
  ViewParams = {}
  Height = 256
  Layers = [org.geoserver.wms.MapLayerInfo@anonymised.com]
  Styles = [StyleImpl[ name=Style_ICC_SA_DMA]]
  Sld = null
  SldBody = null
  SldVersion = null
  ValidateSchema = false
  Filters = null
  Filter = null
  CQLFilter = null
  FeatureId = null
  Transparent = false
  Tiled = true
  TilesOrigin = Point2D.Double[-8.18, 54.555]
  Buffer = 0
  Palette = null
  Width = 256
  KMScore = 40
  KMattr = true
  Legend = false
  Time = null
  Elevation = NaN
  FeatureVersion = null
  RemoteOwsType = null
  RemoteOwsURL = null
  MaxFeatures = null
  StartIndex = null
  Angle = 0.0
  Get = true
  Request = GetMap
  Version = 1.1.1
  BaseUrl = http://ip:80/geoserver/
  RawKvp = {BBOX=-4.478,55.4805,-4.246625,55.711875, TILED=true, SERVICE=WMS, HEIGHT=256, REQUEST=GetMap, STYLES=, TILESORIGIN=-8.18,54.555, WIDTH=256, EXCEPTIONS=application/vnd.ogc.se_inimage, VERSION=1.1.1, FORMAT=image/png, LAYERS=icc_sa:ICC_SA_LAYER_DMA_COVERAGE, SRS=EPSG:4326}
  RequestCharset = UTF-8
2014-07-10 11:55:06,668 DEBUG [org.geoserver.wms.map] - Writing png image ...
2014-07-10 11:55:06,731 DEBUG [org.geoserver.wms.map] - Writing png image ... done!
2014-07-10 11:55:06,731 DEBUG [org.geoserver.wms.map] - Writing png image ... done!
2014-07-10 11:55:06,746 DEBUG [org.geoserver.wms.map] - Writing png image ... done!
2014-07-10 11:55:06,746 DEBUG [org.geoserver.filters] - filtering http://ip:80/geoserver/icc_sa/wms
2014-07-10 11:55:06,746 INFO [org.geoserver.wms] -
Request: getServiceInfo
2014-07-10 11:55:06,746 DEBUG [org.geoserver.ows] - Getting layers and styles from LAYERS and STYLES
2014-07-10 11:55:06,746 DEBUG [org.geoserver.ows] - establishing Style_ICC_SA_DMA style for icc_sa:ICC_SA_LAYER_DMA_COVERAGE
2014-07-10 11:55:06,762 DEBUG [org.geoserver.wms] - Tiled request detected, activating on the fly meta tiler
2014-07-10 11:55:06,762 DEBUG [org.geoserver.wms] - setting up map
2014-07-10 11:55:06,762 DEBUG [org.geoserver.wms.map] - Writing png image ... done!
2014-07-10 11:55:06,777 DEBUG [org.geoserver.wms.map] - Writing png image ... done!
2014-07-10 11:55:06,824 DEBUG [org.geoserver.wms.map] - Looked for meta tile 15, 3in cache: hit!
2014-07-10 11:55:06,824 INFO [org.geoserver.wms] -
Request: getMap
  Bbox = ReferencedEnvelope[-4.709375 : -4.478, 55.4805 : 55.711875]
  BgColor = java.awt.Color[r=255,g=255,b=255]
  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"]]
  SRS = EPSG:4326
  Exceptions = application/vnd.ogc.se_inimage
  Format = image/png
  FormatOptions = {}
  Env = {}
  ViewParams = {}
  Height = 256
  Layers = [org.geoserver.wms.MapLayerInfo@anonymised.com]
  Styles = [StyleImpl[ name=Style_ICC_SA_DMA]]
  Sld = null
  SldBody = null
  SldVersion = null
  ValidateSchema = false
  Filters = null
  Filter = null
  CQLFilter = null
  FeatureId = null
  Transparent = false
  Tiled = true
  TilesOrigin = Point2D.Double[-8.18, 54.555]
  Buffer = 0
  Palette = null
  Width = 256
  KMScore = 40
  KMattr = true
  Legend = false
  Time = null
  Elevation = NaN
  FeatureVersion = null
  RemoteOwsType = null
  RemoteOwsURL = null
  MaxFeatures = null
  StartIndex = null
  Angle = 0.0
  Get = true
  Request = GetMap
  Version = 1.1.1
  BaseUrl = http://ip:80/geoserver/
  RawKvp = {BBOX=-4.709375,55.4805,-4.478,55.711875, TILED=true, SERVICE=WMS, HEIGHT=256, REQUEST=GetMap, STYLES=, TILESORIGIN=-8.18,54.555, WIDTH=256, EXCEPTIONS=application/vnd.ogc.se_inimage, VERSION=1.1.1, FORMAT=image/png, LAYERS=icc_sa:ICC_SA_LAYER_DMA_COVERAGE, SRS=EPSG:4326}
  RequestCharset = UTF-8
2014-07-10 11:55:06,824 DEBUG [org.geoserver.wms.map] - Writing png image ...
2014-07-10 11:55:06,887 DEBUG [org.geoserver.wms.map] - Writing png image ... done!
2014-07-10 11:55:17,184 DEBUG [org.geoserver.filters] - filtering http://ip:80/geoserver/web/
2014-07-10 11:55:17,215 DEBUG [org.geoserver.filters] - filtering http://ip:80/geoserver/web/

Thanks & Regards,
Samyajit Talukdar
Privileged/Confidential information may be contained in this Email and any files transmitted with it. If you are not the intended recipient you should not retain, copy or use this Email for any purpose or disclose all or part of its contents to any person. If you have received this Email in error please notify the postmaster and sender immediately and delete this Email from your system.

Opinions, conclusions and other information in this message that do not relate to the official business of Scottish Water ("SW"), Scottish Water Solutions Ltd ("SWS") or Scottish Water Solutions 2 Ltd ("SWS2") shall be understood as neither given nor endorsed by them. The contents of Emails sent and received by SW, SWS and SWS2 are monitored.

WARNING: Although SW, SWS and SWS2 have taken reasonable precautions to ensure no viruses or other malicious software are present, SW, SWS and SWS2 cannot accept responsibility for any loss or damage arising from the use of this Email or attachments however caused. The recipient should therefore check this Email and any attachments for the presence of viruses or other malicious software.

Scottish Water
www.scottishwater.co.uk
www.scottishwatersolutions.co.uk
postmaster@anonymised.com

Dear Samyajit ,
you'd need to switch to werbose or geotools_developer to also see the
queries and have a clearer idea about what is going on.

Anyway, being GeoServer 2.1 I believe one way or the other you'll have
to consider upgrading at a certain point.

Regards,
Simone Giannecchini

GeoServer Professional Services from the experts! Visit
http://goo.gl/NWWaa2 for more information.

Ing. Simone Giannecchini
@simogeo
Founder/Director

GeoSolutions S.A.S.
Via Poggio alle Viti 1187
55054 Massarosa (LU)
Italy
phone: +39 0584 962313
fax: +39 0584 1660272
mob: +39 333 8128928

http://www.geo-solutions.it
http://twitter.com/geosolutions_it

-------------------------------------------------------

Hi Simone,
Turning on verbose logging gave me a fair idea about the steps being executed. Yes, I understand the version of Geo Server we are using is quite old and upgrade is required. However it is not happening soon.

Thanks & Regards,

Samyajit Talukdar

________________________________________
From: simboss1@anonymised.com [simboss1@anonymised.com] on behalf of Simone Giannecchini [simone.giannecchini@anonymised.com]
Sent: 10 July 2014 12:34
To: Samyajit Talukdar
Cc: geoserver-users@lists.sourceforge.net
Subject: Re: [Geoserver-users] GeoServer 2.1 image generation taking long time

Dear Samyajit ,
you'd need to switch to werbose or geotools_developer to also see the
queries and have a clearer idea about what is going on.

Anyway, being GeoServer 2.1 I believe one way or the other you'll have
to consider upgrading at a certain point.

Regards,
Simone Giannecchini

GeoServer Professional Services from the experts! Visit
http://goo.gl/NWWaa2 for more information.

Ing. Simone Giannecchini
@simogeo
Founder/Director

GeoSolutions S.A.S.
Via Poggio alle Viti 1187
55054 Massarosa (LU)
Italy
phone: +39 0584 962313
fax: +39 0584 1660272
mob: +39 333 8128928

http://www.geo-solutions.it
http://twitter.com/geosolutions_it

-------------------------------------------------------

Privileged/Confidential information may be contained in this Email and any files transmitted with it. If you are not the intended recipient you should not retain, copy or use this Email for any purpose or disclose all or part of its contents to any person. If you have received this Email in error please notify the postmaster and sender immediately and delete this Email from your system.

Opinions, conclusions and other information in this message that do not relate to the official business of Scottish Water ("SW"), Scottish Water Solutions Ltd ("SWS") or Scottish Water Solutions 2 Ltd ("SWS2") shall be understood as neither given nor endorsed by them. The contents of Emails sent and received by SW, SWS and SWS2 are monitored.

WARNING: Although SW, SWS and SWS2 have taken reasonable precautions to ensure no viruses or other malicious software are present, SW, SWS and SWS2 cannot accept responsibility for any loss or damage arising from the use of this Email or attachments however caused. The recipient should therefore check this Email and any attachments for the presence of viruses or other malicious software.

Scottish Water
www.scottishwater.co.uk
www.scottishwatersolutions.co.uk
postmaster@anonymised.com

Hi,
I've couple of questions:
1. I've an installation of Geoserver 2.1 and control flow module extension is not installed on it. Now the client is sending quite a high number of WMS requests to the Geoserver. I would like to know how many WMS requests are handled in parallel by GeoServer by default and which parameter specifies then number of requests that can be put on standby in the queue.
2. The client application is overlaying the shape layer images retrieved over WMS request on Bing maps. Is it correct that for a tiled request, the number of WMS calls to retrieve the coverage image will be equal to the number of tiles into which the screen bounding box area has been down into?

Any help would be highly appreciated.

Thanks & Regards,
Samyajit Talukdar

Privileged/Confidential information may be contained in this Email and any files transmitted with it. If you are not the intended recipient you should not retain, copy or use this Email for any purpose or disclose all or part of its contents to any person. If you have received this Email in error please notify the postmaster and sender immediately and delete this Email from your system.

Opinions, conclusions and other information in this message that do not relate to the official business of Scottish Water ("SW"), Scottish Water Solutions Ltd ("SWS") or Scottish Water Solutions 2 Ltd ("SWS2") shall be understood as neither given nor endorsed by them. The contents of Emails sent and received by SW, SWS and SWS2 are monitored.

WARNING: Although SW, SWS and SWS2 have taken reasonable precautions to ensure no viruses or other malicious software are present, SW, SWS and SWS2 cannot accept responsibility for any loss or damage arising from the use of this Email or attachments however caused. The recipient should therefore check this Email and any attachments for the presence of viruses or other malicious software.

Scottish Water
www.scottishwater.co.uk
www.scottishwatersolutions.co.uk
postmaster@anonymised.com

On Fri, Aug 8, 2014 at 9:30 PM, Samyajit Talukdar <
Samyajit.Talukdar@anonymised.com> wrote:

Hi,
I've couple of questions:
1. I've an installation of Geoserver 2.1 and control flow module extension
is not installed on it. Now the client is sending quite a high number of
WMS requests to the Geoserver. I would like to know how many WMS requests
are handled in parallel by GeoServer by default and which parameter
specifies then number of requests that can be put on standby in the queue.

If you don't have control-flow GeoServer will not perform any control over
the requests. But they java web container normally will.
Tomcat will process by default 200 requests in parallel (way too many
unless you have 50-100 cpu cores in your server), if instead
you have the usual Jetty installation (what you get with the windows
installer, and which is meant for demo, not production), 250
it seems.

2. The client application is overlaying the shape layer images retrieved
over WMS request on Bing maps. Is it correct that for a tiled request, the
number of WMS calls to retrieve the coverage image will be equal to the
number of tiles into which the screen bounding box area has been down into?

This is correct, yes

Cheers
Andrea

--

GeoServer Professional Services from the experts! Visit
http://goo.gl/NWWaa2 for more information.

Ing. Andrea Aime
@geowolf
Technical Lead

GeoSolutions S.A.S.
Via Poggio alle Viti 1187
55054 Massarosa (LU)
Italy
phone: +39 0584 962313
fax: +39 0584 1660272
mob: +39 339 8844549

http://www.geo-solutions.it
http://twitter.com/geosolutions_it

-------------------------------------------------------

Thanks Andrea. GeoServer is installed as an application on a Weblogic Managed server in a Windows environment. So is it that the number of WMS requests running in parallel will be equal to the default threadpool count set up for the managed server? From where can I get the control-flow module for GeoServer2.1, it seems to be unavailable in sourceforge site?

Thanks & Regards,

Samyajit Talukdar

From: andrea.aime@anonymised.com [andrea.aime@anonymised.com] on behalf of Andrea Aime [andrea.aime@anonymised.com]
Sent: 08 August 2014 21:08
To: Samyajit Talukdar
Cc: geoserver-users@lists.sourceforge.net
Subject: Re: [Geoserver-users] GeoServer 2.1 concurrent WMS request processing

On Fri, Aug 8, 2014 at 9:30 PM, Samyajit Talukdar <Samyajit.Talukdar@anonymised.com2...<mailto:Samyajit.Talukdar@anonymised.com>> wrote:

Hi,
I've couple of questions:
1. I've an installation of Geoserver 2.1 and control flow module extension is not installed on it. Now the client is sending quite a high number of WMS requests to the Geoserver. I would like to know how many WMS requests are handled in parallel by GeoServer by default and which parameter specifies then number of requests that can be put on standby in the queue.

If you don't have control-flow GeoServer will not perform any control over the requests. But they java web container normally will.
Tomcat will process by default 200 requests in parallel (way too many unless you have 50-100 cpu cores in your server), if instead
you have the usual Jetty installation (what you get with the windows installer, and which is meant for demo, not production), 250
it seems.

2. The client application is overlaying the shape layer images retrieved over WMS request on Bing maps. Is it correct that for a tiled request, the number of WMS calls to retrieve the coverage image will be equal to the number of tiles into which the screen bounding box area has been down into?

This is correct, yes

Cheers
Andrea

--

GeoServer Professional Services from the experts! Visit
http://goo.gl/NWWaa2 for more information.

Ing. Andrea Aime
@geowolf
Technical Lead

GeoSolutions S.A.S.
Via Poggio alle Viti 1187
55054 Massarosa (LU)
Italy
phone: +39 0584 962313
fax: +39 0584 1660272
mob: +39 339 8844549

http://www.geo-solutions.it
http://twitter.com/geosolutions_it

-------------------------------------------------------

Privileged/Confidential information may be contained in this Email and any files transmitted with it. If you are not the intended recipient you should not retain, copy or use this Email for any purpose or disclose all or part of its contents to any person. If you have received this Email in error please notify the postmaster and sender immediately and delete this Email from your system.

Opinions, conclusions and other information in this message that do not relate to the official business of Scottish Water ("SW"), Scottish Water Solutions Ltd ("SWS") or Scottish Water Solutions 2 Ltd ("SWS2") shall be understood as neither given nor endorsed by them. The contents of Emails sent and received by SW, SWS and SWS2 are monitored.

WARNING: Although SW, SWS and SWS2 have taken reasonable precautions to ensure no viruses or other malicious software are present, SW, SWS and SWS2 cannot accept responsibility for any loss or damage arising from the use of this Email or attachments however caused. The recipient should therefore check this Email and any attachments for the presence of viruses or other malicious software.

Scottish Water
www.scottishwater.co.uk
www.scottishwatersolutions.co.uk
postmaster@anonymised.com

On Sat, Aug 9, 2014 at 10:31 AM, Samyajit Talukdar <
Samyajit.Talukdar@anonymised.com> wrote:

Thanks Andrea. GeoServer is installed as an application on a Weblogic
Managed server in a Windows environment. So is it that the number of WMS
requests running in parallel will be equal to the default threadpool count
set up for the managed server?

Correct.

From where can I get the control-flow module for GeoServer2.1, it seems to
be unavailable in sourceforge site?

It's here:
http://sourceforge.net/projects/geoserver/files/GeoServer/2.1.0/extensions/

In particular:
http://sourceforge.net/projects/geoserver/files/GeoServer/2.1.0/extensions/geoserver-2.1.0-control-flow-plugin.zip/download

(assuming you are using 2.1.0, otherwise go pick the extension specific to
the version of GeoServer you're using).

By the way, control-flow got a number of improvement and fixes since 3
years ago (2.1.0 was released in 2011),
I'd suggest an upgrade of the whole GeoServer.

Cheers
Andrea

--

GeoServer Professional Services from the experts! Visit
http://goo.gl/NWWaa2 for more information.

Ing. Andrea Aime
@geowolf
Technical Lead

GeoSolutions S.A.S.
Via Poggio alle Viti 1187
55054 Massarosa (LU)
Italy
phone: +39 0584 962313
fax: +39 0584 1660272
mob: +39 339 8844549

http://www.geo-solutions.it
http://twitter.com/geosolutions_it

-------------------------------------------------------