search

mapfish / mapfish-print

A component of MapFish for printing templated cartographic maps. This module is the Java serverside module.
http://mapfish.github.io/mapfish-print-doc/
BSD 2-Clause "Simplified" License
184 stars 417 forks source link

The print doesn't work on Windows #277

Closed sbrunner closed 9 years ago

sbrunner commented 9 years ago

All the informations I have ...

Je viens de faire un test et… à chaque fois que le print me fait un échec, alors cela me sature complètement le processus Tomcat. Ce dernier me bouffe un CPU qui tourne à 100% et environ 1GB de mémoire. Ensuite, la seule manière de le réinitialiser c'est de tuer le processus (je ne peux même plus redémarrer proprement le service…). Cela m'inquiète un peu car ce n'est absolument pas stable pour de la production…

Avez-vous les mêmes problèmes avec Tomcat ? Pour info, j'utilise la version du print telle que proposée dans 1.6.0rc1

presse-papiers-1

sbrunner commented 9 years ago

tomcat7-stdout


13:36:05.279 [http-bio-8080-exec-1] INFO  o.m.p.s.ServletMapPrinterFactory - Configuration file modified. Reloading...
13:36:05.282 [http-bio-8080-exec-1] INFO  o.m.p.s.ServletMapPrinterFactory - Loading configuration file: file:/C:/ApacheTomcat7/webapps/print-michael/print-apps/sitn/config.yaml
13:51:59.253 [http-bio-8080-exec-6] INFO  p.mapfish.print.servlet.spec - spec:
    {"outputFormat":"pdf","layout":"A4 portrait","attributes":{"title":"","legend":{"classes":[{"classes":[{"name":"Administration","icons":["http://localhost/michael/wsgi/mapserv_proxy?cache_version=620773170dad4401998ae9bed5b2ce58&TRANSPARENT=TRUE&SERVICE=WMS&VERSION=1.1.1&REQUEST=GetLegendGraphic&EXCEPTIONS=application%2Fvnd.ogc.se_xml&LAYER=poi_administration&FORMAT=image%2Fpng&SCALE=100000"]},{"name":"Culture / loisirs","icons":["http://localhost/michael/wsgi/mapserv_proxy?cache_version=620773170dad4401998ae9bed5b2ce58&TRANSPARENT=TRUE&SERVICE=WMS&VERSION=1.1.1&REQUEST=GetLegendGraphic&EXCEPTIONS=application%2Fvnd.ogc.se_xml&LAYER=poi_culture&FORMAT=image%2Fpng&SCALE=100000"]},{"name":"Points de collectes / Déchetterie","icons":["http://localhost/michael/wsgi/mapserv_proxy?cache_version=620773170dad4401998ae9bed5b2ce58&TRANSPARENT=TRUE&SERVICE=WMS&VERSION=1.1.1&REQUEST=GetLegendGraphic&EXCEPTIONS=application%2Fvnd.ogc.se_xml&LAYER=poi_dechet&FORMAT=image%2Fpng&SCALE=100000"]},{"name":"Santé","icons":["http://localhost/michael/wsgi/mapserv_proxy?cache_version=620773170dad4401998ae9bed5b2ce58&TRANSPARENT=TRUE&SERVICE=WMS&VERSION=1.1.1&REQUEST=GetLegendGraphic&EXCEPTIONS=application%2Fvnd.ogc.se_xml&LAYER=poi_sante&FORMAT=image%2Fpng&SCALE=100000"]},{"name":"Services","icons":["http://localhost/michael/wsgi/mapserv_proxy?cache_version=620773170dad4401998ae9bed5b2ce58&TRANSPARENT=TRUE&SERVICE=WMS&VERSION=1.1.1&REQUEST=GetLegendGraphic&EXCEPTIONS=application%2Fvnd.ogc.se_xml&LAYER=poi_services&FORMAT=image%2Fpng&SCALE=100000"]},{"name":"Sport","icons":["http://localhost/michael/wsgi/mapserv_proxy?cache_version=620773170dad4401998ae9bed5b2ce58&TRANSPARENT=TRUE&SERVICE=WMS&VERSION=1.1.1&REQUEST=GetLegendGraphic&EXCEPTIONS=application%2Fvnd.ogc.se_xml&LAYER=poi_sport&FORMAT=image%2Fpng&SCALE=100000"]},{"name":"Transports","icons":["http://localhost/michael/wsgi/mapserv_proxy?cache_version=620773170dad4401998ae9bed5b2ce58&TRANSPARENT=TRUE&SERVICE=WMS&VERSION=1.1.1&REQUEST=GetLegendGraphic&EXCEPTIONS=application%2Fvnd.ogc.se_xml&LAYER=poi_transport&FORMAT=image%2Fpng&SCALE=100000"]}],"name":""}]},"map":{"center":[546500,206500],"scale":100000,"rotation":0,"layers":[{"customParams":{"TRANSPARENT":true},"imageFormat":"image/png","layers":["poi_transport","poi_sport","poi_services","poi_sante","poi_dechet","poi_culture","poi_administration"],"opacity":1,"baseURL":"http://localhost/michael/wsgi/mapserv_proxy?cache_version=620773170dad4401998ae9bed5b2ce58","styles":[""],"type":"wms"}],"projection":"EPSG:21781","dpi":254,"longitudeFirst":true},"debug":"","datasource":[],"comments":""}}
13:51:59.273 [PrintJobManager-1] DEBUG o.mapfish.print.parser.MapfishParser - No postConstruct method on parameter object.
13:51:59.273 [PrintJobManager-1] DEBUG o.mapfish.print.parser.MapfishParser - No postConstruct method on parameter object.
13:51:59.273 [PrintJobManager-1] DEBUG o.mapfish.print.parser.MapfishParser - No postConstruct method on parameter object.
13:51:59.273 [PrintJobManager-1] DEBUG o.mapfish.print.parser.MapfishParser - No postConstruct method on parameter object.
13:51:59.273 [PrintJobManager-1] DEBUG o.mapfish.print.parser.MapfishParser - No postConstruct method on parameter object.
13:51:59.273 [PrintJobManager-1] DEBUG o.mapfish.print.parser.MapfishParser - No postConstruct method on parameter object.
13:51:59.273 [PrintJobManager-1] DEBUG o.mapfish.print.parser.MapfishParser - No postConstruct method on parameter object.
13:51:59.273 [PrintJobManager-1] DEBUG o.mapfish.print.parser.MapfishParser - No postConstruct method on parameter object.
13:51:59.273 [PrintJobManager-1] DEBUG o.mapfish.print.parser.MapfishParser - No postConstruct method on parameter object.
13:51:59.273 [PrintJobManager-1] DEBUG o.mapfish.print.parser.MapfishParser - No postConstruct method on parameter object.
13:51:59.283 [PrintJobManager-1] DEBUG o.mapfish.print.parser.MapfishParser - Executing postConstruct method on parameter object.
13:52:01.123 [PrintJobManager-1] DEBUG o.mapfish.print.parser.MapfishParser - Executing postConstruct method on parameter object.
13:52:01.893 [PrintJobManager-1] DEBUG o.mapfish.print.parser.MapfishParser - Executing postConstruct method on parameter object.
13:52:01.893 [PrintJobManager-1] DEBUG o.mapfish.print.parser.MapfishParser - Executing postConstruct method on parameter object.
13:52:01.893 [ForkJoinPool-1-worker-1] DEBUG o.m.p.p.ProcessorDependencyGraph - Starting to execute processor graph: 
+ JasperReportBuilder(C:\ApacheTomcat7\webapps\print-michael\print-apps\sitn\.)
+ CompositeClientHttpRequestFactoryProcessor
  +-- CreateMapProcessor
  +-- LegendProcessor
  +-- CreateNorthArrowProcessor
  +-- CreateMapProcessor
+ CreateScalebarProcessor
+ DataSourceProcessor
13:52:01.893 [ForkJoinPool-1-worker-2] INFO  o.m.p.processor.ProcessorGraphNode - Executing process: CompositeClientHttpRequestFactoryProcessor
13:52:01.893 [ForkJoinPool-1-worker-1] INFO  o.m.p.processor.ProcessorGraphNode - Executing process: JasperReportBuilder(C:\ApacheTomcat7\webapps\print-michael\print-apps\sitn\.)
13:52:01.893 [ForkJoinPool-1-worker-4] INFO  o.m.p.processor.ProcessorGraphNode - Executing process: CreateScalebarProcessor
13:52:01.893 [ForkJoinPool-1-worker-3] INFO  o.m.p.processor.ProcessorGraphNode - Executing process: DataSourceProcessor
13:52:01.903 [ForkJoinPool-1-worker-3] INFO  o.m.p.processor.ProcessorGraphNode - Succeeded in executing process: DataSourceProcessor
13:52:01.903 [ForkJoinPool-1-worker-1] DEBUG o.m.p.p.jasper.JasperReportBuilder - Destination file is already up to date: C:\ApacheTomcat7\temp\mapfish-print\sitn\jasper-bin\.\A4_Portrait.jasper
13:52:01.903 [ForkJoinPool-1-worker-1] DEBUG o.m.p.p.jasper.JasperReportBuilder - Destination file is already up to date: C:\ApacheTomcat7\temp\mapfish-print\sitn\jasper-bin\.\legend.jasper
13:52:01.903 [ForkJoinPool-1-worker-1] DEBUG o.m.p.p.jasper.JasperReportBuilder - Destination file is already up to date: C:\ApacheTomcat7\temp\mapfish-print\sitn\jasper-bin\.\results.jasper
13:52:01.903 [ForkJoinPool-1-worker-1] INFO  o.m.p.processor.ProcessorGraphNode - Succeeded in executing process: JasperReportBuilder(C:\ApacheTomcat7\webapps\print-michael\print-apps\sitn\.)
13:52:01.903 [ForkJoinPool-1-worker-3] DEBUG o.m.p.processor.ProcessorGraphNode - Time taken to run processor: 'class org.mapfish.print.processor.jasper.DataSourceProcessor' was 4 ms
13:52:01.903 [ForkJoinPool-1-worker-1] DEBUG o.m.p.processor.ProcessorGraphNode - Time taken to run processor: 'class org.mapfish.print.processor.jasper.JasperReportBuilder' was 4 ms
13:52:01.903 [ForkJoinPool-1-worker-2] INFO  o.m.p.processor.ProcessorGraphNode - Succeeded in executing process: CompositeClientHttpRequestFactoryProcessor
13:52:01.903 [ForkJoinPool-1-worker-2] DEBUG o.m.p.processor.ProcessorGraphNode - Time taken to run processor: 'class org.mapfish.print.processor.http.CompositeClientHttpRequestFactoryProcessor' was 5 ms
13:52:01.903 [ForkJoinPool-1-worker-3] INFO  o.m.p.processor.ProcessorGraphNode - Executing process: LegendProcessor
13:52:01.903 [ForkJoinPool-1-worker-5] INFO  o.m.p.processor.ProcessorGraphNode - Executing process: CreateNorthArrowProcessor
13:52:01.903 [ForkJoinPool-1-worker-2] INFO  o.m.p.processor.ProcessorGraphNode - Executing process: CreateMapProcessor
13:52:01.913 [ForkJoinPool-1-worker-3] DEBUG o.m.p.h.ConfigFileResolvingHttpRequestFactory - Executing http request: http://localhost/michael/wsgi/mapserv_proxy?cache_version=620773170dad4401998ae9bed5b2ce58&TRANSPARENT=TRUE&SERVICE=WMS&VERSION=1.1.1&REQUEST=GetLegendGraphic&EXCEPTIONS=application%2Fvnd.ogc.se_xml&LAYER=poi_administration&FORMAT=image%2Fpng&SCALE=100000
13:52:01.913 [ForkJoinPool-1-worker-5] DEBUG o.m.p.h.ConfigFileResolvingHttpRequestFactory - Resolved request: file:///north.svg using mapfish print config file loaders.
13:52:01.983 [ForkJoinPool-1-worker-2] DEBUG o.m.p.h.ConfigFileResolvingHttpRequestFactory - Executing http request: http://localhost/michael/wsgi/mapserv_proxy?TRANSPARENT=true&BBOX=536710.4166666666%2C194593.75%2C556289.5833333333%2C218406.25&CACHE_VERSION=620773170dad4401998ae9bed5b2ce58&VERSION=1.1.1&FORMAT=image%2Fpng&SERVICE=WMS&HEIGHT=2381&REQUEST=GetMap&LAYERS=poi_transport%2Cpoi_sport%2Cpoi_services%2Cpoi_sante%2Cpoi_dechet%2Cpoi_culture%2Cpoi_administration&STYLES=%2C%2C%2C%2C%2C%2C&SRS=EPSG%3A21781&WIDTH=1957
13:52:02.253 [ForkJoinPool-1-worker-3] DEBUG o.m.p.h.ConfigFileResolvingHttpRequestFactory - Executing http request: http://localhost/michael/wsgi/mapserv_proxy?cache_version=620773170dad4401998ae9bed5b2ce58&TRANSPARENT=TRUE&SERVICE=WMS&VERSION=1.1.1&REQUEST=GetLegendGraphic&EXCEPTIONS=application%2Fvnd.ogc.se_xml&LAYER=poi_culture&FORMAT=image%2Fpng&SCALE=100000
13:52:02.413 [ForkJoinPool-1-worker-3] DEBUG o.m.p.h.ConfigFileResolvingHttpRequestFactory - Executing http request: http://localhost/michael/wsgi/mapserv_proxy?cache_version=620773170dad4401998ae9bed5b2ce58&TRANSPARENT=TRUE&SERVICE=WMS&VERSION=1.1.1&REQUEST=GetLegendGraphic&EXCEPTIONS=application%2Fvnd.ogc.se_xml&LAYER=poi_dechet&FORMAT=image%2Fpng&SCALE=100000
13:52:02.443 [ForkJoinPool-1-worker-4] INFO  o.m.p.processor.ProcessorGraphNode - Succeeded in executing process: CreateScalebarProcessor
13:52:02.443 [ForkJoinPool-1-worker-5] INFO  o.m.p.processor.ProcessorGraphNode - Succeeded in executing process: CreateNorthArrowProcessor
13:52:02.443 [ForkJoinPool-1-worker-4] DEBUG o.m.p.processor.ProcessorGraphNode - Time taken to run processor: 'class org.mapfish.print.processor.map.scalebar.CreateScalebarProcessor' was 548 ms
13:52:02.443 [ForkJoinPool-1-worker-5] DEBUG o.m.p.processor.ProcessorGraphNode - Time taken to run processor: 'class org.mapfish.print.processor.map.CreateNorthArrowProcessor' was 541 ms
13:52:02.563 [ForkJoinPool-1-worker-3] DEBUG o.m.p.h.ConfigFileResolvingHttpRequestFactory - Executing http request: http://localhost/michael/wsgi/mapserv_proxy?cache_version=620773170dad4401998ae9bed5b2ce58&TRANSPARENT=TRUE&SERVICE=WMS&VERSION=1.1.1&REQUEST=GetLegendGraphic&EXCEPTIONS=application%2Fvnd.ogc.se_xml&LAYER=poi_sante&FORMAT=image%2Fpng&SCALE=100000
13:52:02.713 [ForkJoinPool-1-worker-3] DEBUG o.m.p.h.ConfigFileResolvingHttpRequestFactory - Executing http request: http://localhost/michael/wsgi/mapserv_proxy?cache_version=620773170dad4401998ae9bed5b2ce58&TRANSPARENT=TRUE&SERVICE=WMS&VERSION=1.1.1&REQUEST=GetLegendGraphic&EXCEPTIONS=application%2Fvnd.ogc.se_xml&LAYER=poi_services&FORMAT=image%2Fpng&SCALE=100000
13:52:02.843 [ForkJoinPool-1-worker-3] DEBUG o.m.p.h.ConfigFileResolvingHttpRequestFactory - Executing http request: http://localhost/michael/wsgi/mapserv_proxy?cache_version=620773170dad4401998ae9bed5b2ce58&TRANSPARENT=TRUE&SERVICE=WMS&VERSION=1.1.1&REQUEST=GetLegendGraphic&EXCEPTIONS=application%2Fvnd.ogc.se_xml&LAYER=poi_sport&FORMAT=image%2Fpng&SCALE=100000
13:52:02.883 [ForkJoinPool-1-worker-2] DEBUG o.m.print.map.style.StyleParser - Contenu non autorisé dans le prologue.
13:52:02.883 [ForkJoinPool-1-worker-2] DEBUG o.m.p.h.ConfigFileResolvingHttpRequestFactory - Executing http request: raster
13:52:02.883 [ForkJoinPool-1-worker-2] DEBUG o.m.p.h.ConfigFileResolvingHttpRequestFactory - Executing http request: raster
13:52:02.973 [ForkJoinPool-1-worker-3] DEBUG o.m.p.h.ConfigFileResolvingHttpRequestFactory - Executing http request: http://localhost/michael/wsgi/mapserv_proxy?cache_version=620773170dad4401998ae9bed5b2ce58&TRANSPARENT=TRUE&SERVICE=WMS&VERSION=1.1.1&REQUEST=GetLegendGraphic&EXCEPTIONS=application%2Fvnd.ogc.se_xml&LAYER=poi_transport&FORMAT=image%2Fpng&SCALE=100000
13:52:03.093 [ForkJoinPool-1-worker-3] DEBUG o.m.p.p.jasper.JasperReportBuilder - Destination file is already up to date: C:\ApacheTomcat7\temp\mapfish-print\sitn\jasper-bin\legend.jasper
13:52:03.093 [ForkJoinPool-1-worker-3] INFO  o.m.p.processor.ProcessorGraphNode - Succeeded in executing process: LegendProcessor
13:52:03.093 [ForkJoinPool-1-worker-3] DEBUG o.m.p.processor.ProcessorGraphNode - Time taken to run processor: 'class org.mapfish.print.processor.jasper.LegendProcessor' was 1194 ms
13:52:03.533 [ForkJoinPool-1-worker-2] INFO  o.m.p.processor.ProcessorGraphNode - Succeeded in executing process: CreateMapProcessor
13:52:03.533 [ForkJoinPool-1-worker-2] DEBUG o.m.p.processor.ProcessorGraphNode - Time taken to run processor: 'class org.mapfish.print.processor.map.CreateMapProcessor' was 1634 ms
13:52:03.533 [ForkJoinPool-1-worker-1] DEBUG o.m.p.p.ProcessorDependencyGraph - Finished executing processor graph: 
+ JasperReportBuilder(C:\ApacheTomcat7\webapps\print-michael\print-apps\sitn\.)
+ CompositeClientHttpRequestFactoryProcessor
  +-- CreateMapProcessor
  +-- LegendProcessor
  +-- CreateNorthArrowProcessor
  +-- CreateMapProcessor
+ CreateScalebarProcessor
+ DataSourceProcessor
13:52:03.543 [PrintJobManager-1] INFO  o.mapfish.print.output.ValuesLogger - 
This log message details the parameters available for use in the Jasper templates for
  Mapfish Template: A4 portrait
  Jasper Template name: A4_Portrait.jrxml
  The following parameters are available for use in the templates: 
    * layerGraphics (java.util.ArrayList)
    * pdfConfig (org.mapfish.print.config.PDFConfig)
    * scalebarSubReport (java.lang.String)
    * graphic (java.net.URI)
    * numberOfLegendRows (java.lang.Integer)
    * northArrowSubReport (java.lang.String)
    * jrDataSource (net.sf.jasperreports.engine.JREmptyDataSource)
      - This value is a Jasper Reports DataSource and thus can be passed to a subtemplate as a DataSource and used in the subtemplate's detail band.
      - This datasource is not a type that can be introspected but it can be used in a detail section of sub-template if the structure is known.
    * mapSubReport (java.lang.String)
    * clientHttpRequestFactory (org.mapfish.print.processor.http.MapUriProcessor$1)
    * template (org.mapfish.print.config.Template)
    * tempTaskDirectory (java.io.File)
    * legend (net.sf.jasperreports.engine.data.JRTableModelDataSource)
      - This value is a Jasper Reports DataSource and thus can be passed to a subtemplate as a DataSource and used in the subtemplate's detail band.
      - This datasource is not a type that can be introspected but it can be used in a detail section of sub-template if the structure is known.
    * comments (java.lang.String)
    * legendSubReport (java.lang.String)
    * requestHeaders (org.mapfish.print.attribute.HttpRequestHeadersAttribute$Value)
    * scalebar (org.mapfish.print.attribute.ScalebarAttribute$ScalebarAttributeValues)
    * mapContext (org.mapfish.print.attribute.map.MapfishMapContext)
    * title (java.lang.String)
    * map (org.mapfish.print.attribute.map.MapAttribute$MapAttributeValues)
    * debug (java.lang.Integer)
    * SUBREPORT_DIR (java.lang.String)
    * datasource (org.mapfish.print.attribute.DataSourceAttribute$DataSourceAttributeValue)
    * northArrow (org.mapfish.print.attribute.NorthArrowAttribute$NorthArrowAttributeValues)

13:52:03.813 [PrintJobManager-1] DEBUG o.m.p.h.ConfigFileResolvingHttpRequestFactory - Resolved request: logo.png using mapfish print config file loaders.
13:52:03.863 [gmf16_A4_portrait subreports #1] DEBUG o.m.p.h.ConfigFileResolvingHttpRequestFactory - Resolved request: C:/ApacheTomcat7/temp/mapfish-print/task-2634757632004283965tmp/dc7d184c-a596-451c-9958-8b5a006f7565_layer_0.png using mapfish print config file loaders.
13:52:03.863 [gmf16_A4_portrait subreports #1] DEBUG o.m.p.h.ConfigFileResolvingHttpRequestFactory - Resolved request: C:/ApacheTomcat7/temp/mapfish-print/task-2634757632004283965tmp/scalebar-graphic-8638053566312646840.tiff using mapfish print config file loaders.
13:52:27.165 [PrintJobManager-1] DEBUG o.mapfish.print.servlet.job.PrintJob - Print Job c5d59a47-ab29-49d5-abf5-14e91fe3762c@8f0fc086-067c-4307-9018-5fac6edd37d3 completed in 27168ms
sbrunner commented 9 years ago

tomcat7-stderr

juin 10, 2015 1:52:01 PM org.hsqldb.persist.Logger logInfoEvent
Infos: Checkpoint start
juin 10, 2015 1:52:01 PM org.hsqldb.persist.Logger logInfoEvent
Infos: Checkpoint end
Exception in thread "AWT-Shutdown" Exception in thread "http-bio-8080-exec-1" Exception in thread "http-bio-8080-exec-3"
sbrunner commented 9 years ago

localhost_access_log

127.0.0.1 - - [10/Jun/2015:13:51:57 +0200] "GET /print-michael/print/sitn/capabilities.json?cache_version=620773170dad4401998ae9bed5b2ce58 HTTP/1.1" 200 3455
127.0.0.1 - - [10/Jun/2015:13:51:59 +0200] "POST /print-michael/print/sitn/report.pdf?cache_version=620773170dad4401998ae9bed5b2ce58 HTTP/1.1" 200 335
127.0.0.1 - - [10/Jun/2015:13:52:01 +0200] "GET /print-michael/print/sitn/status/c5d59a47-ab29-49d5-abf5-14e91fe3762c@8f0fc086-067c-4307-9018-5fac6edd37d3.json?_dc=1433937119266&cache_version=620773170dad4401998ae9bed5b2ce58 HTTP/1.1" 200 151
127.0.0.1 - - [10/Jun/2015:13:52:02 +0200] "GET /print-michael/print/sitn/status/c5d59a47-ab29-49d5-abf5-14e91fe3762c@8f0fc086-067c-4307-9018-5fac6edd37d3.json?_dc=1433937122158&cache_version=620773170dad4401998ae9bed5b2ce58 HTTP/1.1" 200 151
127.0.0.1 - - [10/Jun/2015:13:52:03 +0200] "GET /print-michael/print/sitn/status/c5d59a47-ab29-49d5-abf5-14e91fe3762c@8f0fc086-067c-4307-9018-5fac6edd37d3.json?_dc=1433937123157&cache_version=620773170dad4401998ae9bed5b2ce58 HTTP/1.1" 200 151
127.0.0.1 - - [10/Jun/2015:13:52:04 +0200] "GET /print-michael/print/sitn/status/c5d59a47-ab29-49d5-abf5-14e91fe3762c@8f0fc086-067c-4307-9018-5fac6edd37d3.json?_dc=1433937124157&cache_version=620773170dad4401998ae9bed5b2ce58 HTTP/1.1" 200 151
127.0.0.1 - - [10/Jun/2015:13:52:05 +0200] "GET /print-michael/print/sitn/status/c5d59a47-ab29-49d5-abf5-14e91fe3762c@8f0fc086-067c-4307-9018-5fac6edd37d3.json?_dc=1433937125157&cache_version=620773170dad4401998ae9bed5b2ce58 HTTP/1.1" 200 151
sbrunner commented 9 years ago

catalina

juin 10, 2015 1:52:01 PM org.hsqldb.persist.Logger logInfoEvent
Infos: dataFileCache open start
juin 10, 2015 1:52:01 PM org.hsqldb.persist.Logger logInfoEvent
Infos: Checkpoint start
juin 10, 2015 1:52:01 PM org.hsqldb.persist.Logger logInfoEvent
Infos: Checkpoint end
sbrunner commented 9 years ago

...

Alors je viens d'augmenter la mémoire à 4 GB pour Tomcat, mais cela ne change rien…

sbrunner commented 9 years ago

...

Dès que j'ajoute la flèche du Nord au template et à la config, Tomcat part aux fraises, c'est l'effet "je remplis la mémoire et le processus". J'ai essayé pleins de trucs en modifiant le chemin d'accès au SVG en utilisant: graphic: "north.svg" graphic: file:///north.svg graphic: file://north.svg graphic: "file:///${configDir}/north.svg" graphic: "file://${configDir}/north.svg" Dès que j'ajoute l'échelle (template + config), alors il me sort une erreur: "error":"net.sf.jasperreports.engine.JRException: Image read failed."

sbrunner commented 9 years ago

...

Ici, c'est configuré pour imprimer la barre d'échelle, mais le status me renvoie toujours cela:

{"done":true,"error":"net.sf.jasperreports.engine.JRException: Image read failed.","count":1,"time":2373,"downloadURL":"/print-michael/print/report/e0069225-caac-4c96-9f1c-386cdea1d29c@ec2f19de-ab00-46c3-9358-2a6aaa77444e"}

Pour la fleche du Nord, je viens de refaire un test. Elle n'apparaît simplement pas dans le PDF… et je n'arrive pas à trouver d'erreur dans le log… Il me met simplement rien de significatif dans stdout (ou est-ce que je ne cherche pas au bon endroit…). Par contre, il ne plante plus Tomcat (moi pas comprendre…) C'est bizarre, c'est comme si le print n'arrivait pas à accéder aux différentes ressources qui lui sont mises à dispo…

sbrunner commented 9 years ago

...

Pour la fèche du Nord, dans config.yaml: Sous attributes:

            northArrow: !northArrow
                size: 40
                default:
                    graphic: file:///north.svg

Dans les processors:

        - !createNorthArrow {}

Ensuite, dans le jrxml, je crée un paramètre northArrowSubReport (java.lang.String). Celui-ci est réutilisé dans un sous rapport comme $P{northArrowSubReport}

tsauerwein commented 9 years ago

@kalbermattenm I was able to reproduce the issue with the scalebar on a Windows 7 SP1 system with Tomcat 6.0.44 and Java 1.7.0_79. I will investigate what is causing the problem.

When do you notice the "freezing" effect? When using a scalebar? When using a north-arrow? Always?

For the north-arrow: I tested on a Windows system and you should be able to set the graphic with the following config:

  northArrowDef: !northArrow
    size: 50
    default:
      graphic: "file://north.svg"

Like in this example: https://github.com/mapfish/mapfish-print/blob/b29dcc21afb709a7bcb3abc52e9c715f4c12b350/examples/src/test/resources/examples/print_osm_new_york_EPSG_900913/config.yaml

kalbermattenm commented 9 years ago

I will investigate what is causing the problem.

Cool ! I almost thought that I was crazy, thanks a lot for your investigations.

When do you notice the "freezing" effect? When using a scalebar? When using a north-arrow? Always?

Not sure if this is related, because I made so many tests... What happens is if your conf.yaml file contains errors, then it might happen that the memory usage of tomcat explodes. Tomcat uses more and more memory and you cannot stop it (apart from killing the tomcat.exe process...)

For the north-arrow: I tested on a Windows system and you should be able to set the graphic with the following config:

Thanks ! I hope to be able to give it try on Monday... It is a bit strange, because I am almost sure to also have tested this way of defining the north arrow.

I will also try to make it freeze again (by generating errors...)

Thanks a lot @tsauerwein

tsauerwein commented 9 years ago

I think I found the problem with the scalebar, see https://github.com/mapfish/mapfish-print/pull/282.

Not sure if this is related, because I made so many tests... What happens is if your conf.yaml file contains errors, then it might happen that the memory usage of tomcat explodes. Tomcat uses more and more memory and you cannot stop it (apart from killing the tomcat.exe process...)

Do you always restart Tomcat after redeploying?

kalbermattenm commented 9 years ago

Yes... I implemented that in the c2cgeoportal makefile...

But it might be because I did directly modify the print config of Tomcat's webapps folder (and this might be a really bad idea).

I will try to do my tests using the proper deployment scheme from c2cgeoportal... It could resolve the Tomcat freezing stuff...

kalbermattenm commented 9 years ago

@tsauerwein, I just tried the North arrow thing as you suggest. It seems to have improved a bit, but it still does not print out the North arrow.

Now I get this error:

Caused by: java.util.NoSuchElementException: northArrow or northArrow is a required
property for CreateNorthArrowProcessor and therefore must be defined
in the Request Data or be an output of one of the other processors. 
Available values: [pdfConfig, northArrowDef, clientHttpRequestFactory, 
template, tempTaskDirectory, comments, requestHeaders, title, map,
debug, SUBREPORT_DIR].

In my config file, I just did as you suggested:

...
            northArrowDef: !northArrow
                size: 50
                default:
                     graphic: "file://north.svg"
...
        processors:
        - !createNorthArrow {}
...

Did I miss something ?

tsauerwein commented 9 years ago

If you name the north-arrow attribute northArrowDef you would have to provide an inputMapper because by default the north-arrrow processor expects an attribute with the name northArrow:

- !createNorthArrow
    inputMapper: {northArrowDef: northArrow}
kalbermattenm commented 9 years ago

OK, sorry... I should have looked more carefully at what I was doing... Now I simply added:

 northArrow: !northArrow

So I do not need any inputMapper.

This is what I did... And this time Tomcat has gone wild... (this is the freezing effect...)

I do not get why it goes wild, because I do not see anything in the logs (hereunder), which could give me a hint...

I ran the request at 9.57am and Tomcat is still running a full process like shown in the image hereunder...

presse-papiers-1

And in the logs 

09:57:48.447 [http-bio-8080-exec-1] INFO  p.mapfish.print.servlet.spec - spec:
    {"outputFormat":"pdf","layout":"A4 portrait","attributes":{"title":"","map":{"center":[551800,206250],"scale":100000,"rotation":0,"layers":[{"customParams":{"TRANSPARENT":true},"imageFormat":"image/png","layers":["poi_administration"],"opacity":1,"baseURL":"http://localhost/michael/wsgi/mapserv_proxy?cache_version=8adfb5c5a46d47c2b490f676e51e45ee","styles":[""],"type":"wms"}],"projection":"EPSG:21781","dpi":254,"longitudeFirst":true},"debug":"","datasource":[],"comments":""}}
09:57:48.467 [http-bio-8080-exec-1] INFO  o.m.p.s.ServletMapPrinterFactory - Configuration file modified. Reloading...
09:57:48.467 [http-bio-8080-exec-1] INFO  o.m.p.s.ServletMapPrinterFactory - Loading configuration file: file:/C:/ApacheTomcat7/webapps/print-michael/print-apps/sitn/config.yaml
09:57:48.587 [PrintJobManager-1] DEBUG o.mapfish.print.parser.MapfishParser - No postConstruct method on parameter object.
09:57:48.597 [PrintJobManager-1] DEBUG o.mapfish.print.parser.MapfishParser - Executing postConstruct method on parameter object.
09:57:50.327 [PrintJobManager-1] DEBUG o.mapfish.print.parser.MapfishParser - Executing postConstruct method on parameter object.
09:57:51.097 [PrintJobManager-1] DEBUG o.mapfish.print.parser.MapfishParser - Executing postConstruct method on parameter object.
09:57:51.107 [ForkJoinPool-1-worker-1] DEBUG o.m.p.p.ProcessorDependencyGraph - Starting to execute processor graph: 
+ JasperReportBuilder(C:\ApacheTomcat7\webapps\print-michael\print-apps\sitn\.)
+ CompositeClientHttpRequestFactoryProcessor
  +-- CreateMapProcessor
  +-- CreateNorthArrowProcessor
  +-- CreateMapProcessor
09:57:51.107 [ForkJoinPool-1-worker-2] INFO  o.m.p.processor.ProcessorGraphNode - Executing process: CompositeClientHttpRequestFactoryProcessor
09:57:51.107 [ForkJoinPool-1-worker-1] INFO  o.m.p.processor.ProcessorGraphNode - Executing process: JasperReportBuilder(C:\ApacheTomcat7\webapps\print-michael\print-apps\sitn\.)
09:57:51.107 [ForkJoinPool-1-worker-1] DEBUG o.m.p.p.jasper.JasperReportBuilder - Destination file is already up to date: C:\ApacheTomcat7\temp\mapfish-print\sitn\jasper-bin\.\A4_Portrait.jasper
09:57:51.107 [ForkJoinPool-1-worker-1] DEBUG o.m.p.p.jasper.JasperReportBuilder - Destination file is already up to date: C:\ApacheTomcat7\temp\mapfish-print\sitn\jasper-bin\.\A4_Portrait_base.jasper
09:57:51.107 [ForkJoinPool-1-worker-1] DEBUG o.m.p.p.jasper.JasperReportBuilder - Destination file is already up to date: C:\ApacheTomcat7\temp\mapfish-print\sitn\jasper-bin\.\legend.jasper
09:57:51.107 [ForkJoinPool-1-worker-1] DEBUG o.m.p.p.jasper.JasperReportBuilder - Destination file is already up to date: C:\ApacheTomcat7\temp\mapfish-print\sitn\jasper-bin\.\results.jasper
09:57:51.107 [ForkJoinPool-1-worker-1] INFO  o.m.p.processor.ProcessorGraphNode - Succeeded in executing process: JasperReportBuilder(C:\ApacheTomcat7\webapps\print-michael\print-apps\sitn\.)
09:57:51.107 [ForkJoinPool-1-worker-2] INFO  o.m.p.processor.ProcessorGraphNode - Succeeded in executing process: CompositeClientHttpRequestFactoryProcessor
09:57:51.107 [ForkJoinPool-1-worker-1] DEBUG o.m.p.processor.ProcessorGraphNode - Time taken to run processor: 'class org.mapfish.print.processor.jasper.JasperReportBuilder' was 2 ms
09:57:51.107 [ForkJoinPool-1-worker-2] DEBUG o.m.p.processor.ProcessorGraphNode - Time taken to run processor: 'class org.mapfish.print.processor.http.CompositeClientHttpRequestFactoryProcessor' was 2 ms
09:57:51.107 [ForkJoinPool-1-worker-2] INFO  o.m.p.processor.ProcessorGraphNode - Executing process: CreateMapProcessor
09:57:51.107 [ForkJoinPool-1-worker-3] INFO  o.m.p.processor.ProcessorGraphNode - Executing process: CreateNorthArrowProcessor
09:57:51.117 [ForkJoinPool-1-worker-3] DEBUG o.m.p.h.ConfigFileResolvingHttpRequestFactory - Resolved request: file://north.svg using mapfish print config file loaders.
09:57:51.167 [ForkJoinPool-1-worker-2] DEBUG o.m.p.h.ConfigFileResolvingHttpRequestFactory - Executing http request: http://localhost/michael/wsgi/mapserv_proxy?TRANSPARENT=true&BBOX=542010.4166666666%2C194343.75%2C561589.5833333333%2C218156.25&CACHE_VERSION=8adfb5c5a46d47c2b490f676e51e45ee&VERSION=1.1.1&FORMAT=image%2Fpng&SERVICE=WMS&HEIGHT=2381&REQUEST=GetMap&LAYERS=poi_administration&STYLES=&SRS=EPSG%3A21781&WIDTH=1957
09:57:51.577 [ForkJoinPool-1-worker-3] INFO  o.m.p.processor.ProcessorGraphNode - Succeeded in executing process: CreateNorthArrowProcessor
09:57:51.577 [ForkJoinPool-1-worker-3] DEBUG o.m.p.processor.ProcessorGraphNode - Time taken to run processor: 'class org.mapfish.print.processor.map.CreateNorthArrowProcessor' was 470 ms
09:57:51.887 [ForkJoinPool-1-worker-2] DEBUG o.m.print.map.style.StyleParser - Contenu non autorisé dans le prologue.
09:57:51.887 [ForkJoinPool-1-worker-2] DEBUG o.m.p.h.ConfigFileResolvingHttpRequestFactory - Executing http request: raster
09:57:51.887 [ForkJoinPool-1-worker-2] DEBUG o.m.p.h.ConfigFileResolvingHttpRequestFactory - Executing http request: raster
09:57:52.437 [ForkJoinPool-1-worker-2] INFO  o.m.p.processor.ProcessorGraphNode - Succeeded in executing process: CreateMapProcessor
09:57:52.437 [ForkJoinPool-1-worker-2] DEBUG o.m.p.processor.ProcessorGraphNode - Time taken to run processor: 'class org.mapfish.print.processor.map.CreateMapProcessor' was 1325 ms
09:57:52.437 [ForkJoinPool-1-worker-1] DEBUG o.m.p.p.ProcessorDependencyGraph - Finished executing processor graph: 
+ JasperReportBuilder(C:\ApacheTomcat7\webapps\print-michael\print-apps\sitn\.)
+ CompositeClientHttpRequestFactoryProcessor
  +-- CreateMapProcessor
  +-- CreateNorthArrowProcessor
  +-- CreateMapProcessor
09:57:52.437 [PrintJobManager-1] INFO  o.mapfish.print.output.ValuesLogger - 
This log message details the parameters available for use in the Jasper templates for
  Mapfish Template: A4 portrait
  Jasper Template name: A4_Portrait.jrxml
  The following parameters are available for use in the templates: 
    * layerGraphics (java.util.ArrayList)
    * pdfConfig (org.mapfish.print.config.PDFConfig)
    * northArrowSubReport (java.lang.String)
    * mapSubReport (java.lang.String)
    * graphic (java.net.URI)
    * clientHttpRequestFactory (org.mapfish.print.processor.http.MapUriProcessor$1)
    * template (org.mapfish.print.config.Template)
    * tempTaskDirectory (java.io.File)
    * comments (java.lang.String)
    * requestHeaders (org.mapfish.print.attribute.HttpRequestHeadersAttribute$Value)
    * mapContext (org.mapfish.print.attribute.map.MapfishMapContext)
    * title (java.lang.String)
    * map (org.mapfish.print.attribute.map.MapAttribute$MapAttributeValues)
    * debug (java.lang.Integer)
    * SUBREPORT_DIR (java.lang.String)
    * northArrow (org.mapfish.print.attribute.NorthArrowAttribute$NorthArrowAttributeValues)

09:57:52.977 [PrintJobManager-1] DEBUG o.m.p.h.ConfigFileResolvingHttpRequestFactory - Resolved request: logo.png using mapfish print config file loaders.
09:57:53.057 [gmf16_A4_portrait subreports #1] DEBUG o.m.p.h.ConfigFileResolvingHttpRequestFactory - Resolved request: C:/ApacheTomcat7/temp/mapfish-print/task-1734029932396096728tmp/c1d760e2-0c62-4b88-98bf-8a142b105f5a_layer_0.png using mapfish print config file loaders.
09:59:58.776 [Post result to registry] INFO  o.m.p.s.job.ThreadPoolJobManager - Job 77f7f80c-b341-4b62-b953-040d512b84cf@83276152-7ec3-4ba8-973d-9a6830aa4392 is abandoned (no status check within the last 120 seconds)
10:00:01.725 [Post result to registry] INFO  o.m.p.s.job.ThreadPoolJobManager - Cancelling job after timeout 77f7f80c-b341-4b62-b953-040d512b84cf@83276152-7ec3-4ba8-973d-9a6830aa4392
kalbermattenm commented 9 years ago

What happens, is that the client (c2cgeoportal), make status request. The 7-10 first ones are OK (status 200), but then it hangs up and the client does not get anymore an answer to these status request... Moreover I have like 10 status request waiting for an answer in the client app...

At the end all of these unanswered request show a 500 status:

presse-papiers-3

tsauerwein commented 9 years ago

@kalbermattenm Could you please provide your print configuration (including the north-arrow graphic) and the print request, so that we can try to reproduce? Thanks.

kalbermattenm commented 9 years ago

I am out of office today but will try to give you access to these resources by the end of the day..

kalbermattenm commented 9 years ago

@tsauerwein, sorry I was not able to do that yesterday...

I created a zip file containing all the files of the sitn print-apps folder. All files with the suffix _base are the ones coming from c2cgeoportal. (I copied-pasted them to create the ones currently used...)

You can download it here: http://sitn.ne.ch/web/diffusion/mapfish/print/print.zip

The print request would be: http://sitn.ne.ch/web/diffusion/mapfish/print/requestSITN.json

tsauerwein commented 9 years ago

Thanks for the configuration. But unfortunately I wasn't able to reproduce the error locally. The report is printed without any problem.

What version of Java, Tomcat, Windows, 32bit/64bit are you using?

kalbermattenm commented 9 years ago

So... You were able to print out the North arrow, right ?

This is my local configuration:

Do you think it can be a problem with the 64bit version of Java ?

I could try two other things:

kalbermattenm commented 9 years ago

Could you tell me which version of Java you are using on your side ?

tsauerwein commented 9 years ago

Yes, the north-arrow was shown in the report.

I am testing on a VM with Windows 7 SP1, Tomcat 7.0.63 and Java 1.7.0_79 (32bit). I am not sure if it is a 32/64bit problem, I will make a test on a Windows 7 Pro system with 64bit.

Maybe you could try to use the same version of Tomcat and Java (with 64 or 32 bit).

kalbermattenm commented 9 years ago

Ok ! Got it !

Now it is working !

I did some investigation regarding Java... The following Java-Tomcat combination is working:

Before, I was pointing to version 1.7.0_51 of Java, but in the 32bit "Program Files" folder (the one with "x86" in its name). When installing Java x64, the installer also creates a 32bit Virtual Java Machine (do not ask why...)

In the meantime, I did a fresh Java installation (and re-configured Apache Tomcat)

So... now, I do not really know if it works because of Java 1.8 or if it id due to the wrong folder pointing thing.... but it is working...

THANKS a lot @tsauerwein !!!

kalbermattenm commented 9 years ago

So... next... I will wait for https://github.com/mapfish/mapfish-print/pull/282 to be merged and c2cgeoportal to be updated with the latest print-servlet.war to make further tests....

This one was quite tricky... I just hope that this will less painful on our Windows server...

tsauerwein commented 9 years ago

Cool, good news! :) As soon as #282 is merged we can provide you an updated WAR. Let us know if you have any problems on the Windows servers.

kalbermattenm commented 9 years ago

Yes, I will start to do that at the beginning of August when I will be back from holidays ;-)

Thanks again !

kalbermattenm commented 9 years ago

282 fixed this issue, thus it could now be closed.

sbrunner commented 9 years ago

Thanks for your test and your patience @kalbermattenm, closing :-)

kalbermattenm commented 9 years ago

No prob, happy to make it work on Windows ;-) (even if nobody else then me cares about it...)