[Lizmap] Print results in a 504 error

Zizi Jama mazizi.jama at gmail.com
Thu Aug 18 11:38:43 PDT 2022


I have modified the following variable QGSRV_SERVER_TIMEOUT from 20 to 60
I was able to get print to work

I don't think this is ideal - a user has to wait a long time.

I would be happy on how I can improve this.


On Thu, Aug 18, 2022 at 4:38 PM Zizi Jama <mazizi.jama at gmail.com> wrote:

> I have used lizmap-docker-compose on my local machine.
>
> When printing I get a 504 error message.
>
> Here is my server information:
> [image: image.png]
> Here is the error long from the qgis server container:
>
> 2022-08-18 14:21:27,154 ERROR   [1]     Request timeout error
> 2022-08-18 14:21:27,155 REQ     [1]     172.19.0.4      504     POST
>  /ows/   20003   83      Lizmap
> 2022-08-18 14:21:27,155 CRITICAL        [1]     Killed stalled process 6981
> 2022-08-18 14:21:27,230 WARNING [36]    Worker 6981 exited with code -9
> 2022-08-18 14:21:27,237 INFO    [16412] ** Cache: Trust Layer Metadata on
> 2022-08-18 14:21:27,249 DEBUG   [16412] configuring qgis api
> 'landing_page': QGIS_SERVER_LANDING_PAGE_PREFIX = /ows/catalog
> 2022-08-18 14:21:27,250 DEBUG   [16412] configuring qgis api
> 'landing_page': QGIS_SERVER_LANDING_PAGE_PROJECTS_DIRECTORIES =
> /srv/projects
> 2022-08-18 14:21:27,250 DEBUG   [16412] Initializing qgis server
> 2022-08-18 14:21:27,250 INFO    [16412] Starting Qgis application:
> 3.22.6-Białowieża
> QStandardPaths: XDG_RUNTIME_DIR not set, defaulting to '/tmp/runtime-'
> 2022-08-18 14:21:27,276 INFO    [16412] Qgis: Installing cleanup hook
> 2022-08-18 14:21:27,277 INFO    [16412] Qgis: Qgis application
> initialized......
> 2022-08-18 14:21:27,378 DEBUG   [16412] Qgis: Server: Skipping GDAL ECW
> drivers in server.
> Warning 1: Unable to find driver ECW to unload from GDAL_SKIP environment
> variable.
> Warning 1: Unable to find driver ECW to unload from GDAL_SKIP environment
> variable.
> Warning 1: Unable to find driver JP2ECW to unload from GDAL_SKIP
> environment variable.
> 2022-08-18 14:21:27,384 DEBUG   [16412] Qgis: Server: QGIS Server
> Settings:
> 2022-08-18 14:21:27,384 DEBUG   [16412] Qgis: Server:   -
> QGIS_OPTIONS_PATH / '' (Override the default path for user configuration):
> '/srv/etc/qgis' (read from ENVIRONMENT_VARIABLE)
> 2022-08-18 14:21:27,384 DEBUG   [16412] Qgis: Server:   -
> QGIS_SERVER_PARALLEL_RENDERING / '/qgis/parallel_rendering'
> (Activate/Deactivate parallel rendering for WMS getMap request): '1' (read
> from ENVIRONMENT_VARIABLE)
> 2022-08-18 14:21:27,384 DEBUG   [16412] Qgis: Server:   -
> QGIS_SERVER_MAX_THREADS / '/qgis/max_threads' (Number of threads to use
> when parallel rendering is activated): '4' (read from ENVIRONMENT_VARIABLE)
> 2022-08-18 14:21:27,384 DEBUG   [16412] Qgis: Server:   -
> QGIS_SERVER_LOG_LEVEL / '' (Log level): '0' (read from ENVIRONMENT_VARIABLE)
> 2022-08-18 14:21:27,384 DEBUG   [16412] Qgis: Server:   -
> QGIS_SERVER_LOG_FILE / '' (Log file): '' (read from DEFAULT_VALUE)
> 2022-08-18 14:21:27,384 DEBUG   [16412] Qgis: Server:   -
> QGIS_SERVER_LOG_STDERR / '' (Activate/Deactivate logging to stderr):
> 'false' (read from DEFAULT_VALUE)
> 2022-08-18 14:21:27,384 DEBUG   [16412] Qgis: Server:   -
> QGIS_PROJECT_FILE / '' (QGIS project file): '' (read from DEFAULT_VALUE)
> 2022-08-18 14:21:27,384 DEBUG   [16412] Qgis: Server:   -
> QGIS_SERVER_IGNORE_BAD_LAYERS / '' (Ignore bad layers): '1' (read from
> ENVIRONMENT_VARIABLE)
> 2022-08-18 14:21:27,384 DEBUG   [16412] Qgis: Server:   -
> QGIS_SERVER_CACHE_DIRECTORY / '/cache/directory' (Specify the cache
> directory): '/srv/etc/qgis/cache' (read from DEFAULT_VALUE)
> 2022-08-18 14:21:27,384 DEBUG   [16412] Qgis: Server:   -
> QGIS_SERVER_CACHE_SIZE / '/cache/size' (Specify the cache size):
> '268435456' (read from DEFAULT_VALUE)
> 2022-08-18 14:21:27,384 DEBUG   [16412] Qgis: Server:   -
> QGIS_SERVER_SHOW_GROUP_SEPARATOR / '/locale/showGroupSeparator' (Show group
> (thousands) separator): 'false' (read from DEFAULT_VALUE)
> 2022-08-18 14:21:27,384 DEBUG   [16412] Qgis: Server:   -
> QGIS_SERVER_OVERRIDE_SYSTEM_LOCALE / '/locale/userLocale' (Override system
> locale): '' (read from DEFAULT_VALUE)
> 2022-08-18 14:21:27,384 DEBUG   [16412] Qgis: Server:   -
> QGIS_SERVER_WMS_MAX_HEIGHT / '/qgis/max_wms_height' (Maximum height for a
> WMS request. The lower one of this and the project configuration is used.):
> '5000' (read from ENVIRONMENT_VARIABLE)
> 2022-08-18 14:21:27,384 DEBUG   [16412] Qgis: Server:   -
> QGIS_SERVER_WMS_MAX_WIDTH / '/qgis/max_wms_width' (Maximum width for a WMS
> request. The most conservative between this and the project one is used):
> '5000' (read from ENVIRONMENT_VARIABLE)
> 2022-08-18 14:21:27,384 DEBUG   [16412] Qgis: Server:   -
> QGIS_SERVER_API_RESOURCES_DIRECTORY /
> '/qgis/server_api_resources_directory' (Base directory where HTML templates
> and static assets (e.g. images, js and css files) are searched for):
> '/usr/share/qgis/resources/server/api' (read from DEFAULT_VALUE)
> 2022-08-18 14:21:27,384 DEBUG   [16412] Qgis: Server:   -
> QGIS_SERVER_API_WFS3_MAX_LIMIT / '/qgis/server_api_wfs3_max_limit' (Maximum
> value for "limit" in a features request, defaults to 10000): '10000' (read
> from DEFAULT_VALUE)
> 2022-08-18 14:21:27,384 DEBUG   [16412] Qgis: Server:   -
> QGIS_SERVER_TRUST_LAYER_METADATA / '' (Trust layer metadata): 'yes' (read
> from ENVIRONMENT_VARIABLE)
> 2022-08-18 14:21:27,384 DEBUG   [16412] Qgis: Server:   -
> QGIS_SERVER_DISABLE_GETPRINT / '' (Don't load layouts): 'false' (read from
> DEFAULT_VALUE)
> 2022-08-18 14:21:27,384 DEBUG   [16412] Qgis: Server:   -
> QGIS_SERVER_LANDING_PAGE_PROJECTS_DIRECTORIES /
> '/qgis/server_projects_directories' (Directories used by the landing page
> service to find .qgs and .qgz projects): '/srv/projects' (read from
> ENVIRONMENT_VARIABLE)
> 2022-08-18 14:21:27,384 DEBUG   [16412] Qgis: Server:   -
> QGIS_SERVER_LANDING_PAGE_PROJECTS_PG_CONNECTIONS /
> '/qgis/server_projects_pg_connections' (PostgreSQL connection strings used
> by the landing page service to find projects): '' (read from DEFAULT_VALUE)
> 2022-08-18 14:21:27,384 DEBUG   [16412] Qgis: Server:   -
> QGIS_SERVER_LOG_PROFILE / '/qgis/server_log_profile' (Add detailed profile
> information to the logs, only effective when QGIS_SERVER_LOG_LEVEL=0):
> 'false' (read from DEFAULT_VALUE)
> 2022-08-18 14:21:27,384 DEBUG   [16412] Qgis: Server:   -
> QGIS_SERVER_WMTS_SERVICE_URL / '/qgis/server_wmts_service_url' (The default
> WMTS service URL): '' (read from DEFAULT_VALUE)
> 2022-08-18 14:21:27,384 DEBUG   [16412] Qgis: Server:   -
> QGIS_OPTIONS_PATH / '' (): '/srv/etc/qgis' (read from DEFAULT_VALUE)
> 2022-08-18 14:21:27,384 DEBUG   [16412] Qgis: Server:   -
> QGIS_OPTIONS_PATH / '' (): '/srv/etc/qgis' (read from DEFAULT_VALUE)
> 2022-08-18 14:21:27,384 DEBUG   [16412] Qgis: Server:   -
> QGIS_OPTIONS_PATH / '' (): '/srv/etc/qgis' (read from DEFAULT_VALUE)
> 2022-08-18 14:21:27,384 DEBUG   [16412] Qgis: Server:   -
> QGIS_OPTIONS_PATH / '' (): '/srv/etc/qgis' (read from DEFAULT_VALUE)
> 2022-08-18 14:21:27,384 DEBUG   [16412] Qgis: Server:   -
> QGIS_SERVER_LANDING_PAGE_PREFIX /
> '/qgis/server_landing_page_base_url_prefix' (Landing page base URL path
> prefix): '/ows/catalog' (read from ENVIRONMENT_VARIABLE)
> 2022-08-18 14:21:27,385 DEBUG   [16412] Qgis: Server: Ini file used to
> initialize settings: /srv/etc/qgis/QGIS/QGIS3.ini
> 2022-08-18 14:21:27,391 DEBUG   [16412] Qgis: Server: cacheDirectory:
> /srv/etc/qgis/cache/
> 2022-08-18 14:21:27,391 DEBUG   [16412] Qgis: Server: maximumCacheSize:
> 268435456
> 2022-08-18 14:21:27,452 DEBUG   [16412] Qgis: Server: Prefix  PATH: /usr
> 2022-08-18 14:21:27,452 DEBUG   [16412] Qgis: Server: Plugin  PATH:
> /usr/lib/qgis/plugins
> 2022-08-18 14:21:27,452 DEBUG   [16412] Qgis: Server: PkgData PATH:
> /usr/share/qgis
> 2022-08-18 14:21:27,452 DEBUG   [16412] Qgis: Server: User DB PATH:
> /srv/etc/qgis/qgis.db
> 2022-08-18 14:21:27,452 DEBUG   [16412] Qgis: Server: Auth DB PATH:
> /srv/etc/qgis/qgis-auth.db
> 2022-08-18 14:21:27,454 DEBUG   [16412] Qgis: Server: SVG PATHS:
> /usr/share/qgis/svg/:/srv/etc/qgis/svg/
> ERROR: Auth db directory path could not be created
> "Loading native module /usr/lib/qgis/server/libdummy.so"
> 2022-08-18 14:21:27,483 DEBUG   [16412] Qgis: Server: Adding service
> SampleService 1.0
> "Loading native module /usr/lib/qgis/server/liblandingpage.so"
> 2022-08-18 14:21:27,485 DEBUG   [16412] Qgis: : Adding API Landing Page
> "Loading native module /usr/lib/qgis/server/libwcs.so"
> 2022-08-18 14:21:27,488 DEBUG   [16412] Qgis: Server: Adding service WCS
> 1.0.0
> "Loading native module /usr/lib/qgis/server/libwfs.so"
> 2022-08-18 14:21:27,492 DEBUG   [16412] Qgis: Server: Adding service WFS
> 1.1.0
> "Loading native module /usr/lib/qgis/server/libwfs3.so"
> 2022-08-18 14:21:27,495 DEBUG   [16412] Qgis: : Adding API OGC WFS3
> (Draft)
> "Loading native module /usr/lib/qgis/server/libwms.so"
> 2022-08-18 14:21:27,497 DEBUG   [16412] Qgis: Server: Adding service WMS
> 1.3.0
> "Loading native module /usr/lib/qgis/server/libwmts.so"
> 2022-08-18 14:21:27,500 DEBUG   [16412] Qgis: Server: Adding service WMTS
> 1.0.0
> 2022-08-18 14:21:27,500 DEBUG   [16412] Qgis: Server: Server initialized
> 2022-08-18 14:21:27,503 INFO    [16412] Proxy configuration enabled: :0,
> type: DefaultProxy
> 2022-08-18 14:21:27,503 INFO    [16412] Initializing plugins from
> /srv/plugins
> 2022-08-18 14:21:27,504 DEBUG   [16412] Looking for plugin in
> /srv/plugins/configcompiler
> 2022-08-18 14:21:27,504 DEBUG   [16412] Looking for plugin in
> /srv/plugins/formwidget
> 2022-08-18 14:21:27,505 DEBUG   [16412] Looking for plugin in
> /srv/plugins/wfsOutputExtension
> 2022-08-18 14:21:27,514 DEBUG   [16412] Qgis: WfsOutputExtension:
> Temporary directory is /tmp/QGIS_WfsOutputExtension
> 2022-08-18 14:21:27,514 DEBUG   [16412] Qgis: WfsOutputExtension: __init__
> ran in 0.0s
> 2022-08-18 14:21:27,514 INFO    [16412] Loaded plugin wfsOutputExtension
> 2022-08-18 14:21:27,514 DEBUG   [16412] Looking for plugin in
> /srv/plugins/auth
> 2022-08-18 14:21:27,514 DEBUG   [16412] Looking for plugin in
> /srv/plugins/DataPlotly
> 2022-08-18 14:21:27,551 DEBUG   [16412] Qgis: DataPlotly: Custom
> DataPlotly layout item loaded
> 2022-08-18 14:21:27,552 INFO    [16412] Loaded plugin DataPlotly
> 2022-08-18 14:21:27,552 DEBUG   [16412] Looking for plugin in
> /srv/plugins/coord
> 2022-08-18 14:21:27,552 DEBUG   [16412] Looking for plugin in
> /srv/plugins/lizmap
> 2022-08-18 14:21:27,579 DEBUG   [16412] Qgis: Lizmap: Init server version
> "3.7.7"
> 2022-08-18 14:21:27,579 DEBUG   [16412] Qgis: : Adding API Lizmap 3.7.7
> 2022-08-18 14:21:27,579 DEBUG   [16412] Qgis: Lizmap: API "/lizmap" loaded
> with the server info handler
> 2022-08-18 14:21:27,579 DEBUG   [16412] Qgis: Server: Adding service
> EXPRESSION 1.0.0
> 2022-08-18 14:21:27,580 DEBUG   [16412] Qgis: Lizmap: Service "expression"
> loaded
> 2022-08-18 14:21:27,580 DEBUG   [16412] Qgis: Server: Adding service
> LIZMAP 1.0.0
> 2022-08-18 14:21:27,580 DEBUG   [16412] Qgis: Lizmap: Service "lizmap"
> loaded
> 2022-08-18 14:21:27,580 DEBUG   [16412] Qgis: Lizmap: LizmapFilter.init
> 2022-08-18 14:21:27,580 DEBUG   [16412] Qgis: Lizmap: Filter "lizmap"
> loaded
> 2022-08-18 14:21:27,580 DEBUG   [16412] Qgis: Lizmap: Access control
> "lizmap" loaded
> 2022-08-18 14:21:27,580 DEBUG   [16412] Qgis: Lizmap: Filter "get feature
> info" loaded
> 2022-08-18 14:21:27,580 INFO    [16412] Loaded plugin lizmap
> 2022-08-18 14:21:27,580 DEBUG   [16412] Looking for plugin in
> /srv/plugins/tpl
> 2022-08-18 14:21:27,580 DEBUG   [16412] Looking for plugin in
> /srv/plugins/formbuilder
> 2022-08-18 14:21:27,581 DEBUG   [16412] Looking for plugin in
> /srv/plugins/db
> 2022-08-18 14:21:27,581 DEBUG   [16412] Looking for plugin in
> /srv/plugins/.cache_qgis_plugin_manager
> 2022-08-18 14:21:27,582 DEBUG   [16412] Looking for plugin in
> /srv/plugins/lizmap_server
> 2022-08-18 14:21:27,593 DEBUG   [16412] Qgis: Lizmap: Init server version
> "1.0.0"
> 2022-08-18 14:21:27,593 DEBUG   [16412] Qgis: : Adding API Lizmap 1.0.0
> 2022-08-18 14:21:27,593 DEBUG   [16412] Qgis: Lizmap: API "/lizmap" loaded
> with the server info handler
> 2022-08-18 14:21:27,594 WARNING [16412] Qgis: : Error Service EXPRESSION
> 1.0.0 is already registered
> 2022-08-18 14:21:27,594 DEBUG   [16412] Qgis: Lizmap: Service "expression"
> loaded
> 2022-08-18 14:21:27,594 WARNING [16412] Qgis: : Error Service LIZMAP 1.0.0
> is already registered
> 2022-08-18 14:21:27,594 DEBUG   [16412] Qgis: Lizmap: Service "lizmap"
> loaded
> 2022-08-18 14:21:27,594 DEBUG   [16412] Qgis: Lizmap: LizmapFilter.init
> 2022-08-18 14:21:27,594 DEBUG   [16412] Qgis: Lizmap: Filter "lizmap"
> loaded
> 2022-08-18 14:21:27,594 DEBUG   [16412] Qgis: Lizmap: Access control
> "lizmap" loaded
> 2022-08-18 14:21:27,594 DEBUG   [16412] Qgis: Lizmap: Filter "get feature
> info" loaded
> 2022-08-18 14:21:27,594 INFO    [16412] Loaded plugin lizmap_server
> 2022-08-18 14:21:27,594 DEBUG   [16412] Looking for plugin in
> /srv/plugins/sources.list
> 2022-08-18 14:21:27,594 DEBUG   [16412] Connecting to
> ipc:///tmp/qgssrv/broker/1/pool0
> 2022-08-18 14:21:27,595 DEBUG   [16412] Identity set to
> b'\x0c\x0c\xef\x98\x1f\x01\x11\xed\xbbt\x02B\xac\x13\x00\x05'
> 2022-08-18 14:21:27,595 DEBUG   [16412] Enabling broadcast notification
> 2022-08-18 14:21:27,596 INFO    [16412] Starting ZMQ worker loop
> 2022-08-18 14:21:27,596 DEBUG   [35]    READY
> b'\x0c\x0c\xef\x98\x1f\x01\x11\xed\xbbt\x02B\xac\x13\x00\x05'
>
> Any pointers what could be wrong?
>
>
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.osgeo.org/pipermail/lizmap/attachments/20220818/b022ee82/attachment-0001.htm>
-------------- next part --------------
A non-text attachment was scrubbed...
Name: image.png
Type: image/png
Size: 48613 bytes
Desc: not available
URL: <http://lists.osgeo.org/pipermail/lizmap/attachments/20220818/b022ee82/attachment-0001.png>


More information about the Lizmap mailing list