JsReport issues when deployed



  • Hey, I'm having a few strange problems with jsreport 3.2 and Postgres store.
    I can't use jsreport 3.3 right now because of another issue that I posted here: https://forum.jsreport.net/topic/2272/jsreport-3-3-error-with-postgres-store

    1. I had a jsreport 2.11 instance deployed to kubernetes and it was working great.
      I then upgraded to jsreport 3.2 (with a new/empty) postgres DB but for some odd reason almost all the logs are doubled.
      It doesn't happen when I locally run the same jsreport with the same config on my windows machine.
    2022-01-18T19:58:08.405Z - info: Initializing jsreport (version: 3.2.0, configuration file: jsreport.config.json, nodejs: 16.13.1)
    2022-01-18T19:58:08.404Z - info: Initializing jsreport (version: 3.2.0, configuration file: jsreport.config.json, nodejs: 16.13.1)
    2022-01-18T19:58:08.407Z - info: Searching for available extensions in /app/
    2022-01-18T19:58:08.406Z - info: Searching for available extensions in /app/
    2022-01-18T19:58:08.408Z - info: Extensions location cache not found, crawling directories
    2022-01-18T19:58:08.408Z - info: Extensions location cache not found, crawling directories
    2022-01-18T19:58:08.748Z - info: Found 36 extension(s)
    2022-01-18T19:58:08.748Z - info: Found 36 extension(s)
    2022-01-18T19:58:08.772Z - debug: Writing extension locations cache to /tmp/jsreport/core/locations.json
    2022-01-18T19:58:08.773Z - debug: Discovered 36 extensions
    2022-01-18T19:58:08.842Z - info: Using extension authentication@3.1.0
    2022-01-18T19:58:08.842Z - info: Using extension authentication@3.1.0
    2022-01-18T19:58:08.953Z - info: Using extension base@3.0.0
    2022-01-18T19:58:08.953Z - info: Using extension base@3.0.0
    2022-01-18T19:58:08.958Z - info: Using extension child-templates@3.0.1
    2022-01-18T19:58:08.958Z - info: Using extension child-templates@3.0.1
    2022-01-18T19:58:08.959Z - info: Using extension cli@3.0.0
    2022-01-18T19:58:08.959Z - info: Using extension cli@3.0.0
    2022-01-18T19:58:08.961Z - info: Using extension components@3.1.0
    2022-01-18T19:58:08.961Z - info: Using extension components@3.1.0
    2022-01-18T19:58:08.962Z - info: Using extension data@3.0.1
    2022-01-18T19:58:08.962Z - info: Using extension data@3.0.1
    2022-01-18T19:58:08.963Z - info: Using extension express@3.2.0
    2022-01-18T19:58:08.963Z - info: Using extension express@3.2.0
    2022-01-18T19:58:09.153Z - info: Using extension freeze@3.0.1
    2022-01-18T19:58:09.153Z - info: Using extension freeze@3.0.1
    2022-01-18T19:58:09.155Z - info: Using extension fs-store@3.0.0
    2022-01-18T19:58:09.155Z - info: Using extension fs-store@3.0.0
    2022-01-18T19:58:09.206Z - info: Using extension handlebars@3.0.0
    2022-01-18T19:58:09.206Z - info: Using extension handlebars@3.0.0
    2022-01-18T19:58:09.207Z - info: Using extension import-export@3.0.2
    2022-01-18T19:58:09.207Z - info: Using extension import-export@3.0.2
    2022-01-18T19:58:09.215Z - info: Using extension jsrender@3.0.0
    2022-01-18T19:58:09.215Z - info: Using extension jsrender@3.0.0
    2022-01-18T19:58:09.215Z - info: Using extension licensing@3.0.1
    2022-01-18T19:58:09.215Z - info: Using extension licensing@3.0.1
    2022-01-18T19:58:09.228Z - info: Using extension localization@3.1.0
    2022-01-18T19:58:09.228Z - info: Using extension localization@3.1.0
    2022-01-18T19:58:09.229Z - info: Using extension npm@3.1.0
    2022-01-18T19:58:09.229Z - info: Using extension npm@3.1.0
    2022-01-18T19:58:09.238Z - info: Using extension pdf-utils@3.1.0
    2022-01-18T19:58:09.238Z - info: Using extension pdf-utils@3.1.0
    2022-01-18T19:58:09.241Z - info: Using extension postgres-store@3.0.0
    2022-01-18T19:58:09.241Z - info: Using extension postgres-store@3.0.0
    2022-01-18T19:58:09.363Z - info: Using extension reports@3.0.0
    2022-01-18T19:58:09.363Z - info: Using extension reports@3.0.0
    2022-01-18T19:58:09.366Z - info: Using extension tags@3.0.0
    2022-01-18T19:58:09.366Z - info: Using extension tags@3.0.0
    2022-01-18T19:58:09.367Z - info: Using extension text@3.0.0
    2022-01-18T19:58:09.367Z - info: Using extension text@3.0.0
    2022-01-18T19:58:09.368Z - info: Using extension version-control@3.0.1
    2022-01-18T19:58:09.368Z - info: Using extension version-control@3.0.1
    2022-01-18T19:58:09.381Z - info: Using extension assets@3.1.0
    2022-01-18T19:58:09.381Z - info: Using extension assets@3.1.0
    2022-01-18T19:58:09.390Z - info: Using extension authorization@3.1.0
    2022-01-18T19:58:09.390Z - info: Using extension authorization@3.1.0
    2022-01-18T19:58:09.393Z - info: Using extension browser-client@3.1.0
    2022-01-18T19:58:09.393Z - info: Using extension browser-client@3.1.0
    2022-01-18T19:58:09.395Z - info: Using extension chrome-pdf@3.1.0
    2022-01-18T19:58:09.395Z - info: Using extension chrome-pdf@3.1.0
    2022-01-18T19:58:09.398Z - debug: Chrome strategy is chrome-pool, numberOfWorkers: 5
    2022-01-18T19:58:09.399Z - debug: Chrome custom launch options are executablePath=/usr/lib/chromium/chrome, args=--no-sandbox,--disable-dev-shm-usage
    2022-01-18T19:58:09.399Z - info: Using extension docx@3.1.0
    2022-01-18T19:58:09.399Z - info: Using extension docx@3.1.0
    2022-01-18T19:58:09.401Z - info: Using extension html-to-xlsx@3.1.0
    2022-01-18T19:58:09.401Z - info: Using extension html-to-xlsx@3.1.0
    2022-01-18T19:58:09.450Z - info: html-to-xlsx detected chrome as available html engine
    2022-01-18T19:58:09.450Z - info: html-to-xlsx detected chrome as available html engine
    2022-01-18T19:58:09.450Z - info: Using extension pptx@3.1.0
    2022-01-18T19:58:09.450Z - info: Using extension pptx@3.1.0
    2022-01-18T19:58:09.452Z - info: Using extension scheduling@3.0.1
    2022-01-18T19:58:09.452Z - info: Using extension scheduling@3.0.1
    2022-01-18T19:58:09.481Z - info: Using extension scripts@3.1.0
    2022-01-18T19:58:09.480Z - info: Using extension scripts@3.1.0
    2022-01-18T19:58:09.482Z - info: Using extension static-pdf@3.0.0
    2022-01-18T19:58:09.482Z - info: Using extension static-pdf@3.0.0
    2022-01-18T19:58:09.483Z - info: Using extension studio@3.2.0
    2022-01-18T19:58:09.483Z - info: Using extension studio@3.2.0
    2022-01-18T19:58:09.553Z - info: Using extension xlsx@3.1.0
    2022-01-18T19:58:09.553Z - info: Using extension xlsx@3.1.0
    2022-01-18T19:58:09.568Z - info: Using extension public-templates@3.0.0
    2022-01-18T19:58:09.568Z - info: Using extension public-templates@3.0.0
    2022-01-18T19:58:09.569Z - info: Using extension sample-template@3.1.0
    2022-01-18T19:58:09.569Z - info: Using extension sample-template@3.1.0
    2022-01-18T19:58:09.571Z - info: Using extension studio-theme-dark@3.0.1
    2022-01-18T19:58:09.571Z - info: Using extension studio-theme-dark@3.0.1
    2022-01-18T19:58:09.572Z - info: Using general timeout for rendering (reportTimeout: 60000)
    2022-01-18T19:58:09.572Z - info: Using general timeout for rendering (reportTimeout: 60000)
    2022-01-18T19:58:09.572Z - info: Using postgres provider for template store.
    2022-01-18T19:58:09.572Z - info: Using postgres provider for template store.
    2022-01-18T19:58:09.831Z - debug: studio default theme is: light
    2022-01-18T19:58:09.831Z - debug: studio is using custom logo from base64 source
    2022-01-18T19:58:10.051Z - info: Store contains user entities with deprecated username, migrating to name...
    2022-01-18T19:58:10.051Z - info: Store contains user entities with deprecated username, migrating to name...
    2022-01-18T19:58:10.556Z - info: Creating default express app.
    2022-01-18T19:58:10.556Z - info: Creating default express app.
    2022-01-18T19:58:10.572Z - info: jsreport server successfully started on http port: 5588
    2022-01-18T19:58:10.572Z - info: jsreport server successfully started on http port: 5588
    2022-01-18T19:58:10.573Z - info: Verifying license key free
    2022-01-18T19:58:10.573Z - info: Verifying license key free
    2022-01-18T19:58:10.611Z - info: Using free license
    2022-01-18T19:58:10.611Z - info: Using free license
    2022-01-18T19:58:10.731Z - info: Initializing worker threads
    2022-01-18T19:58:10.731Z - info: Initializing worker threads
    2022-01-18T19:58:10.731Z - debug: Extensions in workers: authentication, base, child-templates, components, data, express, handlebars, jsrender, localization, npm, pdf-utils, reports, text, version-control, assets, authorization, browser-client, chrome-pdf, docx, html-to-xlsx, pptx, scripts, static-pdf, studio, xlsx
    2022-01-18T19:58:11.020Z - info: 2 worker threads initialized in 289ms
    2022-01-18T19:58:11.020Z - info: 2 worker threads initialized in 289ms
    2022-01-18T19:58:11.021Z - info: Starting temp files cleanup with 180000ms threshold
    2022-01-18T19:58:11.021Z - info: Starting temp files cleanup with 180000ms threshold
    2022-01-18T19:58:11.021Z - info: reporter initialized
    2022-01-18T19:58:11.021Z - info: reporter initialized
    2022-01-18T20:00:26.648Z - debug: OData query on templates
    2022-01-18T20:00:26.653Z - debug: OData query on folders
    2022-01-18T20:00:26.659Z - debug: OData query on data
    2022-01-18T20:00:26.662Z - debug: OData query on tags
    2022-01-18T20:00:26.665Z - debug: OData query on assets
    2022-01-18T20:00:26.669Z - debug: OData query on components
    2022-01-18T20:00:26.785Z - debug: OData query on scripts
    2022-01-18T20:00:26.931Z - debug: OData query on usersGroups
    2022-01-18T20:00:26.934Z - debug: OData query on users
    2022-01-18T20:00:26.938Z - debug: OData query on schedules
    2022-01-18T20:00:27.062Z - debug: OData query on settings
    2022-01-18T20:00:27.246Z - debug: OData query on templates
    2022-01-18T20:00:32.511Z - info: Starting rendering request 1 (user: root) rootId=2fr0j6eyrtmkcj1, id=2fr0j6eyrtmkcj1
    2022-01-18T20:00:32.511Z - info: Starting rendering request 1 (user: root) rootId=2fr0j6eyrtmkcj1, id=2fr0j6eyrtmkcj1
    2022-01-18T20:00:32.512Z - info: Rendering template { name: tickets-default, recipe: chrome-pdf, engine: handlebars, preview: true } rootId=2fr0j6eyrtmkcj1, id=2fr0j6eyrtmkcj1
    2022-01-18T20:00:32.512Z - info: Rendering template { name: tickets-default, recipe: chrome-pdf, engine: handlebars, preview: true } rootId=2fr0j6eyrtmkcj1, id=2fr0j6eyrtmkcj1
    2022-01-18T20:00:33.204Z - debug: Adding sample data BMnrDZR rootId=2fr0j6eyrtmkcj1, id=2fr0j6eyrtmkcj1
    2022-01-18T20:00:33.205Z - debug: Base url not specified, skipping its injection. rootId=2fr0j6eyrtmkcj1, id=2fr0j6eyrtmkcj1
    2022-01-18T20:00:34.232Z - debug: Replaced assets ["../../watermark.css","test-styles.css","./../images/test_link_logo.svg","./../images/blank_logo.svg","./../images/route_icon.svg","./../images/arrow_icon.svg","./../images/od_icon.svg","./../images/date_icon.svg","./../images/time_icon.svg","./../images/person_icon.svg","./../images/train_icon.svg","./../images/class_icon.svg","./../images/coach_icon.svg","./../images/seat_icon.svg","./../images/wheelchair_icon.svg"] rootId=2fr0j6eyrtmkcj1, id=2fr0j6eyrtmkcj1
    2022-01-18T20:00:34.690Z - debug: Replaced assets ["/test/fonts/semibold.ttf","/test/fonts/regular.ttf"] rootId=2fr0j6eyrtmkcj1, id=2fr0j6eyrtmkcj1
    2022-01-18T20:00:34.801Z - debug: Replaced assets ["test-helper-functions.js"] rootId=2fr0j6eyrtmkcj1, id=2fr0j6eyrtmkcj1
    2022-01-18T20:00:34.801Z - debug: Rendering engine handlebars rootId=2fr0j6eyrtmkcj1, id=2fr0j6eyrtmkcj1
    2022-01-18T20:00:35.608Z - debug: Executing recipe chrome-pdf rootId=2fr0j6eyrtmkcj1, id=2fr0j6eyrtmkcj1
    2022-01-18T20:00:35.922Z - debug: Converting with chrome HeadlessChrome/93.0.4577.82 using chrome-pool strategy rootId=2fr0j6eyrtmkcj1, id=2fr0j6eyrtmkcj1
    2022-01-18T20:00:35.922Z - debug: Page request: GET (document) file:///tmp/jsreport/autocleanup/21c1d620-a0ad-4718-a3b2-17106ec5210a-chrome-pdf.html rootId=2fr0j6eyrtmkcj1, id=2fr0j6eyrtmkcj1
    2022-01-18T20:00:36.018Z - debug: Page request: GET (image) data:image/svg+xml;base64,PHN2ZyBpZD0iTGF5ZXJfMSIgZGF0YS1uYW1lPSJMYXllciAxIiB4bWxucz0iaHR0cDovL3d3dy... rootId=2fr0j6eyrtmkcj1, id=2fr0j6eyrtmkcj1
    2022-01-18T20:00:36.019Z - debug: Page request finished: GET (image) 200 data:image/svg+xml;base64,PHN2ZyBpZD0iTGF5ZXJfMSIgZGF0YS1uYW1lPSJMYXllciAxIiB4bWxucz0iaHR0cDovL3d3dy... rootId=2fr0j6eyrtmkcj1, id=2fr0j6eyrtmkcj1
    2022-01-18T20:00:36.019Z - debug: Page request: GET (image) data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iNTM5IiBoZWlnaHQ9IjM1IiB4bWxucz0iaHR0cDovL3d3dy53My5vcmcvMj... rootId=2fr0j6eyrtmkcj1, id=2fr0j6eyrtmkcj1
    2022-01-18T20:00:36.019Z - debug: Page request finished: GET (image) 200 data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iNTM5IiBoZWlnaHQ9IjM1IiB4bWxucz0iaHR0cDovL3d3dy53My5vcmcvMj... rootId=2fr0j6eyrtmkcj1, id=2fr0j6eyrtmkcj1
    2022-01-18T20:00:36.020Z - debug: Page request: GET (image) data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iMTIzIiBoZWlnaHQ9IjE3MCIgeG1sbnM9Imh0dHA6Ly93d3cudzMub3JnLz... rootId=2fr0j6eyrtmkcj1, id=2fr0j6eyrtmkcj1
    2022-01-18T20:00:36.020Z - debug: Page request finished: GET (image) 200 data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iMTIzIiBoZWlnaHQ9IjE3MCIgeG1sbnM9Imh0dHA6Ly93d3cudzMub3JnLz... rootId=2fr0j6eyrtmkcj1, id=2fr0j6eyrtmkcj1
    2022-01-18T20:00:36.020Z - debug: Page request: GET (image) data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iMjkiIGhlaWdodD0iMTAiIHhtbG5zPSJodHRwOi8vd3d3LnczLm9yZy8yMD... rootId=2fr0j6eyrtmkcj1, id=2fr0j6eyrtmkcj1
    2022-01-18T20:00:36.021Z - debug: Page request finished: GET (image) 200 data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iMjkiIGhlaWdodD0iMTAiIHhtbG5zPSJodHRwOi8vd3d3LnczLm9yZy8yMD... rootId=2fr0j6eyrtmkcj1, id=2fr0j6eyrtmkcj1
    2022-01-18T20:00:36.021Z - debug: Page request: GET (image) data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iNzUiIGhlaWdodD0iNzguNzMiIHhtbG5zPSJodHRwOi8vd3d3LnczLm9yZy... rootId=2fr0j6eyrtmkcj1, id=2fr0j6eyrtmkcj1
    2022-01-18T20:00:36.022Z - debug: Page request finished: GET (image) 200 data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iNzUiIGhlaWdodD0iNzguNzMiIHhtbG5zPSJodHRwOi8vd3d3LnczLm9yZy... rootId=2fr0j6eyrtmkcj1, id=2fr0j6eyrtmkcj1
    2022-01-18T20:00:36.022Z - debug: Page request finished: GET (document) file:///tmp/jsreport/autocleanup/21c1d620-a0ad-4718-a3b2-17106ec5210a-chrome-pdf.html rootId=2fr0j6eyrtmkcj1, id=2fr0j6eyrtmkcj1
    2022-01-18T20:00:36.119Z - debug: Page request: GET (font) data:font/ttf;base64,AAEAAAAPAIAAAwBwRkZUTYSLY84AAiesAAAAHEdERUYJMQupAAEbCAAAADZHUE9TukgglQABMfAAAPW6R1NVQjF/R20AARtAAAAWrk9TLzJr0XaZAAABeAAAAGBjbWFwgtKRBgAADOQAAAR2Z2FzcP//AAMAARsAAAAACGdseWYsBK6xAAAW5AAA5VxoZWFkEfGAogAAAPwAAAA2aGhlYQfTBbwAAAE0AAAAJGhtdHjOWDcbAAAB2AAACwxsb2NhDjFOIAAAEVwAAAWIbWF4cAPIChYAAAFYAAAAIG5hbWWca0aZAAD8QAAACLVwb3N08mvPcwABBPgAABYHAAEAAAADAELnx3lfXw889QALA+gAAAAA1z2Z4QAAAADXqaLk/o3+zgVbA+gAAQAIAAIAAAAAAAAAAQAAA+j+9AAABXf+jf6NBVsAAQAAAAAAAAAAAAAAAAAAAsMAAQAAAsMKEwDDAAAAAAACAAAAAQABAAAAQAAAAAAAAAADAl0CWAAFAAACvAKKAAAAjAK8AooAAAHdAEkBEwAAAgsHBAAAAAAAAKAAAn9QAAA7AAAAIAAAAABTd1R5ACAAIP7/Atz+9AAAA+gBDCAAAJcAAAAAAfQCxgAAACAADAKEAEgAAAAAAU0AAADkAAABIAArAbIAOgLWACACGAAoA9QAHAMCAD8A6AA6ASsAJwEqABYB7AAfAewAJAEaABsBkAA5ARcAJwIiABICdwAzAXAAAgI9ACkCTAAjAn0AEgI8AB8COQAjAh8AIgJMACoCOQAnASsAMQEuACYB3QAaAh0APAHdABsCBwAcAyMAKALnAAECegBPAwwAJgLMAE8COABPAh4ATwMlACYC9ABPAS8ATwIjABQCtgBPAicATwN0AE8DGABPAygAJQJPAE8DSAAlApAATwJRACICOAAVAtAARwLRAAMDwQASArn/+gKyAA... rootId=2fr0j6eyrtmkcj1, id=2fr0j6eyrtmkcj1
    2022-01-18T20:00:36.122Z - debug: Page request: GET (image) data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iNzUiIGhlaWdodD0iNzIuOTk5OTk5OTk5OTk5OTkiIHhtbG5zPSJodHRwOi... rootId=2fr0j6eyrtmkcj1, id=2fr0j6eyrtmkcj1
    2022-01-18T20:00:36.122Z - debug: Page request finished: GET (image) 200 data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iNzUiIGhlaWdodD0iNzIuOTk5OTk5OTk5OTk5OTkiIHhtbG5zPSJodHRwOi... rootId=2fr0j6eyrtmkcj1, id=2fr0j6eyrtmkcj1
    2022-01-18T20:00:36.122Z - debug: Page request finished: GET (image) 200 data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iNzUiIGhlaWdodD0iODAuNSIgeG1sbnM9Imh0dHA6Ly93d3cudzMub3JnLz... rootId=2fr0j6eyrtmkcj1, id=2fr0j6eyrtmkcj1
    2022-01-18T20:00:36.123Z - debug: Page request finished: GET (image) 200 data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iNzUiIGhlaWdodD0iODYiIHhtbG5zPSJodHRwOi8vd3d3LnczLm9yZy8yMD... rootId=2fr0j6eyrtmkcj1, id=2fr0j6eyrtmkcj1
    2022-01-18T20:00:36.123Z - debug: Page request: GET (image) data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iNzUiIGhlaWdodD0iODAuNSIgeG1sbnM9Imh0dHA6Ly93d3cudzMub3JnLz... rootId=2fr0j6eyrtmkcj1, id=2fr0j6eyrtmkcj1
    2022-01-18T20:00:36.123Z - debug: Page request: GET (image) data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iNzUiIGhlaWdodD0iODYiIHhtbG5zPSJodHRwOi8vd3d3LnczLm9yZy8yMD... rootId=2fr0j6eyrtmkcj1, id=2fr0j6eyrtmkcj1
    2022-01-18T20:00:36.123Z - debug: Page request: GET (image) data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iNjgiIGhlaWdodD0iNzUiIHhtbG5zPSJodHRwOi8vd3d3LnczLm9yZy8yMD... rootId=2fr0j6eyrtmkcj1, id=2fr0j6eyrtmkcj1
    2022-01-18T20:00:36.124Z - debug: Page request finished: GET (image) 200 data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iNjgiIGhlaWdodD0iNzUiIHhtbG5zPSJodHRwOi8vd3d3LnczLm9yZy8yMD... rootId=2fr0j6eyrtmkcj1, id=2fr0j6eyrtmkcj1
    2022-01-18T20:00:36.124Z - debug: Page request: GET (image) data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iNjgiIGhlaWdodD0iNzkiIHhtbG5zPSJodHRwOi8vd3d3LnczLm9yZy8yMD... rootId=2fr0j6eyrtmkcj1, id=2fr0j6eyrtmkcj1
    2022-01-18T20:00:36.124Z - debug: Page request finished: GET (image) 200 data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iNjgiIGhlaWdodD0iNzkiIHhtbG5zPSJodHRwOi8vd3d3LnczLm9yZy8yMD... rootId=2fr0j6eyrtmkcj1, id=2fr0j6eyrtmkcj1
    2022-01-18T20:00:36.124Z - debug: Page request finished: GET (image) 200 data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iOTkiIGhlaWdodD0iNjMiIHhtbG5zPSJodHRwOi8vd3d3LnczLm9yZy8yMD... rootId=2fr0j6eyrtmkcj1, id=2fr0j6eyrtmkcj1
    2022-01-18T20:00:36.125Z - debug: Page request finished: GET (image) 200 data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iNzAiIGhlaWdodD0iODciIHhtbG5zPSJodHRwOi8vd3d3LnczLm9yZy8yMD... rootId=2fr0j6eyrtmkcj1, id=2fr0j6eyrtmkcj1
    2022-01-18T20:00:36.125Z - debug: Page request: GET (image) data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iOTkiIGhlaWdodD0iNjMiIHhtbG5zPSJodHRwOi8vd3d3LnczLm9yZy8yMD... rootId=2fr0j6eyrtmkcj1, id=2fr0j6eyrtmkcj1
    2022-01-18T20:00:36.125Z - debug: Page request: GET (image) data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iNzAiIGhlaWdodD0iODciIHhtbG5zPSJodHRwOi8vd3d3LnczLm9yZy8yMD... rootId=2fr0j6eyrtmkcj1, id=2fr0j6eyrtmkcj1
    2022-01-18T20:00:36.126Z - debug: Page request: GET (image) data:image/png;base64,iVBORw0KGgoAAAANSUhEUgAAAGkAAABpCAYAAAA5gg06AAAAAXNSR0IArs4c6QAAAARnQU1BAACxjw... rootId=2fr0j6eyrtmkcj1, id=2fr0j6eyrtmkcj1
    2022-01-18T20:00:36.126Z - debug: Page request finished: GET (image) 200 data:image/png;base64,iVBORw0KGgoAAAANSUhEUgAAAGkAAABpCAYAAAA5gg06AAAAAXNSR0IArs4c6QAAAARnQU1BAACxjw... rootId=2fr0j6eyrtmkcj1, id=2fr0j6eyrtmkcj1
    2022-01-18T20:00:36.126Z - debug: Page request finished: GET (image) 200 data:image/png;base64,iVBORw0KGgoAAAANSUhEUgAAAtAAAABkCAIAAAAg8ZCBAAAAAXNSR0IArs4c6QAAAARnQU1BAACxjw... rootId=2fr0j6eyrtmkcj1, id=2fr0j6eyrtmkcj1
    2022-01-18T20:00:36.126Z - debug: Page request: GET (image) data:image/png;base64,iVBORw0KGgoAAAANSUhEUgAAAtAAAABkCAIAAAAg8ZCBAAAAAXNSR0IArs4c6QAAAARnQU1BAACxjw... rootId=2fr0j6eyrtmkcj1, id=2fr0j6eyrtmkcj1
    2022-01-18T20:00:36.127Z - debug: Page request finished: GET (font) 200 data:font/ttf;base64,AAEAAAAPAIAAAwBwRkZUTYSLY84AAiesAAAAHEdERUYJMQupAAEbCAAAADZHUE9TukgglQABMfAAAPW6R1NVQjF/R20AARtAAAAWrk9TLzJr0XaZAAABeAAAAGBjbWFwgtKRBgAADOQAAAR2Z2FzcP//AAMAARsAAAAACGdseWYsBK6xAAAW5AAA5VxoZWFkEfGAogAAAPwAAAA2aGhlYQfTBbwAAAE0AAAAJGhtdHjOWDcbAAAB2AAACwxsb2NhDjFOIAAAEVwAAAWIbWF4cAPIChYAAAFYAAAAIG5hbWWca0aZAAD8QAAACLVwb3N08mvPcwABBPgAABYHAAEAAAADAELnx3lfXw889QALA+gAAAAA1z2Z4QAAAADXqaLk/o3+zgVbA+gAAQAIAAIAAAAAAAAAAQAAA+j+9AAABXf+jf6NBVsAAQAAAAAAAAAAAAAAAAAAAsMAAQAAAsMKEwDDAAAAAAACAAAAAQABAAAAQAAAAAAAAAADAl0CWAAFAAACvAKKAAAAjAK8AooAAAHdAEkBEwAAAgsHBAAAAAAAAKAAAn9QAAA7AAAAIAAAAABTd1R5ACAAIP7/Atz+9AAAA+gBDCAAAJcAAAAAAfQCxgAAACAADAKEAEgAAAAAAU0AAADkAAABIAArAbIAOgLWACACGAAoA9QAHAMCAD8A6AA6ASsAJwEqABYB7AAfAewAJAEaABsBkAA5ARcAJwIiABICdwAzAXAAAgI9ACkCTAAjAn0AEgI8AB8COQAjAh8AIgJMACoCOQAnASsAMQEuACYB3QAaAh0APAHdABsCBwAcAyMAKALnAAECegBPAwwAJgLMAE8COABPAh4ATwMlACYC9ABPAS8ATwIjABQCtgBPAicATwN0AE8DGABPAygAJQJPAE8DSAAlApAATwJRACICOAAVAtAARwLRAAMDw... rootId=2fr0j6eyrtmkcj1, id=2fr0j6eyrtmkcj1
    2022-01-18T20:00:36.127Z - debug: Running chrome with params {"printBackground":true,"format":"A4","marginTop":"30px","marginBottom":"30px","mediaType":"print","timeout":60000,"margin":{"top":"30px","bottom":"30px"}} rootId=2fr0j6eyrtmkcj1, id=2fr0j6eyrtmkcj1
    2022-01-18T20:00:36.271Z - debug: Skipping storing report. rootId=2fr0j6eyrtmkcj1, id=2fr0j6eyrtmkcj1
    2022-01-18T20:00:36.271Z - info: Rendering request 1 finished in 3833 ms rootId=2fr0j6eyrtmkcj1, id=2fr0j6eyrtmkcj1
    2022-01-18T20:00:36.271Z - info: Rendering request 1 finished in 3833 ms rootId=2fr0j6eyrtmkcj1, id=2fr0j6eyrtmkcj1
    2022-01-18T20:00:56.290Z - info: Starting rendering request 2 (user: root) rootId=1sp7y5p88rsuf5b, id=1sp7y5p88rsuf5b
    2022-01-18T20:00:56.290Z - info: Starting rendering request 2 (user: root) rootId=1sp7y5p88rsuf5b, id=1sp7y5p88rsuf5b
    2022-01-18T20:00:56.291Z - info: Rendering template { name: tickets-default, recipe: chrome-pdf, engine: handlebars, preview: true } rootId=1sp7y5p88rsuf5b, id=1sp7y5p88rsuf5b
    2022-01-18T20:00:56.290Z - info: Rendering template { name: tickets-default, recipe: chrome-pdf, engine: handlebars, preview: true } rootId=1sp7y5p88rsuf5b, id=1sp7y5p88rsuf5b
    2022-01-18T20:00:56.397Z - debug: Adding sample data BMnrDZR rootId=1sp7y5p88rsuf5b, id=1sp7y5p88rsuf5b
    2022-01-18T20:00:56.959Z - debug: Base url not specified, skipping its injection. rootId=1sp7y5p88rsuf5b, id=1sp7y5p88rsuf5b
    2022-01-18T20:00:58.021Z - debug: Replaced assets ["../../watermark.css","test-styles.css","./../images/test_link_logo.svg","./../images/blank_logo.svg","./../images/route_icon.svg","./../images/arrow_icon.svg","./../images/od_icon.svg","./../images/date_icon.svg","./../images/time_icon.svg","./../images/person_icon.svg","./../images/train_icon.svg","./../images/class_icon.svg","./../images/coach_icon.svg","./../images/seat_icon.svg","./../images/wheelchair_icon.svg"] rootId=1sp7y5p88rsuf5b, id=1sp7y5p88rsuf5b
    2022-01-18T20:00:58.418Z - debug: Replaced assets ["/test/fonts/semibold.ttf","/test/fonts/regular.ttf"] rootId=1sp7y5p88rsuf5b, id=1sp7y5p88rsuf5b
    2022-01-18T20:00:58.597Z - debug: Replaced assets ["test-helper-functions.js"] rootId=1sp7y5p88rsuf5b, id=1sp7y5p88rsuf5b
    2022-01-18T20:00:58.597Z - debug: Rendering engine handlebars rootId=1sp7y5p88rsuf5b, id=1sp7y5p88rsuf5b
    2022-01-18T20:00:58.734Z - debug: Executing recipe chrome-pdf rootId=1sp7y5p88rsuf5b, id=1sp7y5p88rsuf5b
    2022-01-18T20:00:58.933Z - debug: Converting with chrome HeadlessChrome/93.0.4577.82 using chrome-pool strategy rootId=1sp7y5p88rsuf5b, id=1sp7y5p88rsuf5b
    2022-01-18T20:00:59.034Z - debug: Page request: GET (document) file:///tmp/jsreport/autocleanup/527e237c-898a-4192-b069-85b3dac5c5f8-chrome-pdf.html rootId=1sp7y5p88rsuf5b, id=1sp7y5p88rsuf5b
    2022-01-18T20:00:59.035Z - debug: Page request finished: GET (document) file:///tmp/jsreport/autocleanup/527e237c-898a-4192-b069-85b3dac5c5f8-chrome-pdf.html rootId=1sp7y5p88rsuf5b, id=1sp7y5p88rsuf5b
    2022-01-18T20:00:59.139Z - debug: Page request: GET (image) data:image/svg+xml;base64,PHN2ZyBpZD0iTGF5ZXJfMSIgZGF0YS1uYW1lPSJMYXllciAxIiB4bWxucz0iaHR0cDovL3d3dy... rootId=1sp7y5p88rsuf5b, id=1sp7y5p88rsuf5b
    2022-01-18T20:00:59.140Z - debug: Page request finished: GET (image) 200 data:image/svg+xml;base64,PHN2ZyBpZD0iTGF5ZXJfMSIgZGF0YS1uYW1lPSJMYXllciAxIiB4bWxucz0iaHR0cDovL3d3dy... rootId=1sp7y5p88rsuf5b, id=1sp7y5p88rsuf5b
    2022-01-18T20:00:59.140Z - debug: Page request: GET (image) data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iNTM5IiBoZWlnaHQ9IjM1IiB4bWxucz0iaHR0cDovL3d3dy53My5vcmcvMj... rootId=1sp7y5p88rsuf5b, id=1sp7y5p88rsuf5b
    2022-01-18T20:00:59.141Z - debug: Page request finished: GET (image) 200 data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iNTM5IiBoZWlnaHQ9IjM1IiB4bWxucz0iaHR0cDovL3d3dy53My5vcmcvMj... rootId=1sp7y5p88rsuf5b, id=1sp7y5p88rsuf5b
    2022-01-18T20:00:59.141Z - debug: Page request: GET (image) data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iMTIzIiBoZWlnaHQ9IjE3MCIgeG1sbnM9Imh0dHA6Ly93d3cudzMub3JnLz... rootId=1sp7y5p88rsuf5b, id=1sp7y5p88rsuf5b
    2022-01-18T20:00:59.141Z - debug: Page request finished: GET (image) 200 data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iMTIzIiBoZWlnaHQ9IjE3MCIgeG1sbnM9Imh0dHA6Ly93d3cudzMub3JnLz... rootId=1sp7y5p88rsuf5b, id=1sp7y5p88rsuf5b
    2022-01-18T20:00:59.142Z - debug: Page request: GET (image) data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iMjkiIGhlaWdodD0iMTAiIHhtbG5zPSJodHRwOi8vd3d3LnczLm9yZy8yMD... rootId=1sp7y5p88rsuf5b, id=1sp7y5p88rsuf5b
    2022-01-18T20:00:59.142Z - debug: Page request finished: GET (image) 200 data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iMjkiIGhlaWdodD0iMTAiIHhtbG5zPSJodHRwOi8vd3d3LnczLm9yZy8yMD... rootId=1sp7y5p88rsuf5b, id=1sp7y5p88rsuf5b
    2022-01-18T20:00:59.142Z - debug: Page request: GET (image) data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iNzUiIGhlaWdodD0iNzguNzMiIHhtbG5zPSJodHRwOi8vd3d3LnczLm9yZy... rootId=1sp7y5p88rsuf5b, id=1sp7y5p88rsuf5b
    2022-01-18T20:00:59.142Z - debug: Page request finished: GET (image) 200 data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iNzUiIGhlaWdodD0iNzguNzMiIHhtbG5zPSJodHRwOi8vd3d3LnczLm9yZy... rootId=1sp7y5p88rsuf5b, id=1sp7y5p88rsuf5b
    2022-01-18T20:00:59.143Z - debug: Page request: GET (font) data:font/ttf;base64,AAEAAAAPAIAAAwBwRkZUTYSLY84AAiesAAAAHEdERUYJMQupAAEbCAAAADZHUE9TukgglQABMfAAAPW6R1NVQjF/R20AARtAAAAWrk9TLzJr0XaZAAABeAAAAGBjbWFwgtKRBgAADOQAAAR2Z2FzcP//AAMAARsAAAAACGdseWYsBK6xAAAW5AAA5VxoZWFkEfGAogAAAPwAAAA2aGhlYQfTBbwAAAE0AAAAJGhtdHjOWDcbAAAB2AAACwxsb2NhDjFOIAAAEVwAAAWIbWF4cAPIChYAAAFYAAAAIG5hbWWca0aZAAD8QAAACLVwb3N08mvPcwABBPgAABYHAAEAAAADAELnx3lfXw889QALA+gAAAAA1z2Z4QAAAADXqaLk/o3+zgVbA+gAAQAIAAIAAAAAAAAAAQAAA+j+9AAABXf+jf6NBVsAAQAAAAAAAAAAAAAAAAAAAsMAAQAAAsMKEwDDAAAAAAACAAAAAQABAAAAQAAAAAAAAAADAl0CWAAFAAACvAKKAAAAjAK8AooAAAHdAEkBEwAAAgsHBAAAAAAAAKAAAn9QAAA7AAAAIAAAAABTd1R5ACAAIP7/Atz+9AAAA+gBDCAAAJcAAAAAAfQCxgAAACAADAKEAEgAAAAAAU0AAADkAAABIAArAbIAOgLWACACGAAoA9QAHAMCAD8A6AA6ASsAJwEqABYB7AAfAewAJAEaABsBkAA5ARcAJwIiABICdwAzAXAAAgI9ACkCTAAjAn0AEgI8AB8COQAjAh8AIgJMACoCOQAnASsAMQEuACYB3QAaAh0APAHdABsCBwAcAyMAKALnAAECegBPAwwAJgLMAE8COABPAh4ATwMlACYC9ABPAS8ATwIjABQCtgBPAicATwN0AE8DGABPAygAJQJPAE8DSAAlApAATwJRACICOAAVAtAARwLRAAMDwQASArn/+gKyAA... rootId=1sp7y5p88rsuf5b, id=1sp7y5p88rsuf5b
    2022-01-18T20:00:59.143Z - debug: Page request: GET (image) data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iNzUiIGhlaWdodD0iNzIuOTk5OTk5OTk5OTk5OTkiIHhtbG5zPSJodHRwOi... rootId=1sp7y5p88rsuf5b, id=1sp7y5p88rsuf5b
    2022-01-18T20:00:59.143Z - debug: Page request finished: GET (image) 200 data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iNzUiIGhlaWdodD0iNzIuOTk5OTk5OTk5OTk5OTkiIHhtbG5zPSJodHRwOi... rootId=1sp7y5p88rsuf5b, id=1sp7y5p88rsuf5b
    2022-01-18T20:00:59.144Z - debug: Page request: GET (image) data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iNzUiIGhlaWdodD0iODAuNSIgeG1sbnM9Imh0dHA6Ly93d3cudzMub3JnLz... rootId=1sp7y5p88rsuf5b, id=1sp7y5p88rsuf5b
    2022-01-18T20:00:59.144Z - debug: Page request finished: GET (image) 200 data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iNzUiIGhlaWdodD0iODAuNSIgeG1sbnM9Imh0dHA6Ly93d3cudzMub3JnLz... rootId=1sp7y5p88rsuf5b, id=1sp7y5p88rsuf5b
    2022-01-18T20:00:59.144Z - debug: Page request: GET (image) data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iNzUiIGhlaWdodD0iODYiIHhtbG5zPSJodHRwOi8vd3d3LnczLm9yZy8yMD... rootId=1sp7y5p88rsuf5b, id=1sp7y5p88rsuf5b
    2022-01-18T20:00:59.145Z - debug: Page request finished: GET (image) 200 data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iNzUiIGhlaWdodD0iODYiIHhtbG5zPSJodHRwOi8vd3d3LnczLm9yZy8yMD... rootId=1sp7y5p88rsuf5b, id=1sp7y5p88rsuf5b
    2022-01-18T20:00:59.145Z - debug: Page request: GET (image) data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iNjgiIGhlaWdodD0iNzUiIHhtbG5zPSJodHRwOi8vd3d3LnczLm9yZy8yMD... rootId=1sp7y5p88rsuf5b, id=1sp7y5p88rsuf5b
    2022-01-18T20:00:59.145Z - debug: Page request finished: GET (image) 200 data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iNjgiIGhlaWdodD0iNzUiIHhtbG5zPSJodHRwOi8vd3d3LnczLm9yZy8yMD... rootId=1sp7y5p88rsuf5b, id=1sp7y5p88rsuf5b
    2022-01-18T20:00:59.146Z - debug: Page request finished: GET (image) 200 data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iNjgiIGhlaWdodD0iNzkiIHhtbG5zPSJodHRwOi8vd3d3LnczLm9yZy8yMD... rootId=1sp7y5p88rsuf5b, id=1sp7y5p88rsuf5b
    2022-01-18T20:00:59.147Z - debug: Page request: GET (image) data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iNjgiIGhlaWdodD0iNzkiIHhtbG5zPSJodHRwOi8vd3d3LnczLm9yZy8yMD... rootId=1sp7y5p88rsuf5b, id=1sp7y5p88rsuf5b
    2022-01-18T20:00:59.147Z - debug: Page request: GET (image) data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iOTkiIGhlaWdodD0iNjMiIHhtbG5zPSJodHRwOi8vd3d3LnczLm9yZy8yMD... rootId=1sp7y5p88rsuf5b, id=1sp7y5p88rsuf5b
    2022-01-18T20:00:59.147Z - debug: Page request finished: GET (image) 200 data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iOTkiIGhlaWdodD0iNjMiIHhtbG5zPSJodHRwOi8vd3d3LnczLm9yZy8yMD... rootId=1sp7y5p88rsuf5b, id=1sp7y5p88rsuf5b
    2022-01-18T20:00:59.148Z - debug: Page request finished: GET (image) 200 data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iNzAiIGhlaWdodD0iODciIHhtbG5zPSJodHRwOi8vd3d3LnczLm9yZy8yMD... rootId=1sp7y5p88rsuf5b, id=1sp7y5p88rsuf5b
    2022-01-18T20:00:59.148Z - debug: Page request finished: GET (image) 200 data:image/png;base64,iVBORw0KGgoAAAANSUhEUgAAAGkAAABpCAYAAAA5gg06AAAAAXNSR0IArs4c6QAAAARnQU1BAACxjw... rootId=1sp7y5p88rsuf5b, id=1sp7y5p88rsuf5b
    2022-01-18T20:00:59.148Z - debug: Page request: GET (image) data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iNzAiIGhlaWdodD0iODciIHhtbG5zPSJodHRwOi8vd3d3LnczLm9yZy8yMD... rootId=1sp7y5p88rsuf5b, id=1sp7y5p88rsuf5b
    2022-01-18T20:00:59.149Z - debug: Page request: GET (image) data:image/png;base64,iVBORw0KGgoAAAANSUhEUgAAAGkAAABpCAYAAAA5gg06AAAAAXNSR0IArs4c6QAAAARnQU1BAACxjw... rootId=1sp7y5p88rsuf5b, id=1sp7y5p88rsuf5b
    2022-01-18T20:00:59.149Z - debug: Page request: GET (image) data:image/png;base64,iVBORw0KGgoAAAANSUhEUgAAAtAAAABkCAIAAAAg8ZCBAAAAAXNSR0IArs4c6QAAAARnQU1BAACxjw... rootId=1sp7y5p88rsuf5b, id=1sp7y5p88rsuf5b
    2022-01-18T20:00:59.149Z - debug: Page request finished: GET (image) 200 data:image/png;base64,iVBORw0KGgoAAAANSUhEUgAAAtAAAABkCAIAAAAg8ZCBAAAAAXNSR0IArs4c6QAAAARnQU1BAACxjw... rootId=1sp7y5p88rsuf5b, id=1sp7y5p88rsuf5b
    2022-01-18T20:00:59.234Z - debug: Page request finished: GET (font) 200 data:font/ttf;base64,AAEAAAAPAIAAAwBwRkZUTYSLY84AAiesAAAAHEdERUYJMQupAAEbCAAAADZHUE9TukgglQABMfAAAPW6R1NVQjF/R20AARtAAAAWrk9TLzJr0XaZAAABeAAAAGBjbWFwgtKRBgAADOQAAAR2Z2FzcP//AAMAARsAAAAACGdseWYsBK6xAAAW5AAA5VxoZWFkEfGAogAAAPwAAAA2aGhlYQfTBbwAAAE0AAAAJGhtdHjOWDcbAAAB2AAACwxsb2NhDjFOIAAAEVwAAAWIbWF4cAPIChYAAAFYAAAAIG5hbWWca0aZAAD8QAAACLVwb3N08mvPcwABBPgAABYHAAEAAAADAELnx3lfXw889QALA+gAAAAA1z2Z4QAAAADXqaLk/o3+zgVbA+gAAQAIAAIAAAAAAAAAAQAAA+j+9AAABXf+jf6NBVsAAQAAAAAAAAAAAAAAAAAAAsMAAQAAAsMKEwDDAAAAAAACAAAAAQABAAAAQAAAAAAAAAADAl0CWAAFAAACvAKKAAAAjAK8AooAAAHdAEkBEwAAAgsHBAAAAAAAAKAAAn9QAAA7AAAAIAAAAABTd1R5ACAAIP7/Atz+9AAAA+gBDCAAAJcAAAAAAfQCxgAAACAADAKEAEgAAAAAAU0AAADkAAABIAArAbIAOgLWACACGAAoA9QAHAMCAD8A6AA6ASsAJwEqABYB7AAfAewAJAEaABsBkAA5ARcAJwIiABICdwAzAXAAAgI9ACkCTAAjAn0AEgI8AB8COQAjAh8AIgJMACoCOQAnASsAMQEuACYB3QAaAh0APAHdABsCBwAcAyMAKALnAAECegBPAwwAJgLMAE8COABPAh4ATwMlACYC9ABPAS8ATwIjABQCtgBPAicATwN0AE8DGABPAygAJQJPAE8DSAAlApAATwJRACICOAAVAtAARwLRAAMDw... rootId=1sp7y5p88rsuf5b, id=1sp7y5p88rsuf5b
    2022-01-18T20:00:59.234Z - debug: Running chrome with params {"printBackground":true,"format":"A4","marginTop":"30px","marginBottom":"30px","mediaType":"print","timeout":60000,"margin":{"top":"30px","bottom":"30px"}} rootId=1sp7y5p88rsuf5b, id=1sp7y5p88rsuf5b
    2022-01-18T20:00:59.353Z - debug: Skipping storing report. rootId=1sp7y5p88rsuf5b, id=1sp7y5p88rsuf5b
    2022-01-18T20:00:59.354Z - info: Rendering request 2 finished in 3130 ms rootId=1sp7y5p88rsuf5b, id=1sp7y5p88rsuf5b
    2022-01-18T20:00:59.354Z - info: Rendering request 2 finished in 3130 ms rootId=1sp7y5p88rsuf5b, id=1sp7y5p88rsuf5b
    


    1. The second issue I am having is about the rendering speed
      I have a template that was rendered in jsreport 2.11 in about 0.5 - 1.5 seconds.
      The same template in jsreport 3.2 takes arount 3-4 seconds.
      For some reason the asset replacing is super slow.
      Is it not caching the assets and asking the assets from DB every time I render?

    One thing to also note here is that this is only slow when deployed to Kubernetes.
    Both examples were using the same template and the same Kubernetes node/pod.

    Debug profiler info in jsreport 3.2:
    0_1642538407423_upload-5900a407-bc84-4da1-a451-f50b427bd42d

    Debug profiler info in jsreport 2.11:
    0_1642538603544_upload-cbefad50-49a6-40c5-9468-608a937b178e

    Here is my jsreport.config.json for jsreport 3.2

    {
        "httpPort": 5588,
        "store": {
          "provider": "postgres"
        },
        "blobStorage": {
          "provider": "fs"
        },
        "logger": {
          "console": {
            "transport": "console",
            "level": "debug"
          },
          "file": {
            "transport": "console",
            "level": "info"
          },
          "error": {
            "transport": "console",
            "level": "error"
          }
        },
        "allowLocalFilesAccess": true,
        "reportTimeout": 60000,
        "templatingEngines": {
          "strategy": "http-server"
        },
        "sandbox": {
          "allowedModules": "*"
        },
        "workers": {
          "numberOfWorkers": 5
        },
        "extensions": {
          "authentication": {
            "cookieSession": {
              "secret": "...",
              "cookie": { "secure": true }
            },
            "admin": {
              "username": "admin",
              "password": "admin"
            },
            "enabled": true
          },
          "sample-template": {
            "createSamples": true
          },
          "scripts": {
            "strategy": "http-server",
            "allowedModules": "*"
          },
          "chrome": {
            "strategy": "chrome-pool",
            "numberOfWorkers": 5 
          },
          "chrome-pdf": {
            "strategy": "chrome-pool",
            "numberOfWorkers": 5 
          },
          "postgres-store": {
            "host": "",
            "port": 5432,
            "database": "",
            "user": "",
            "password": ""
          }
        }
      }
    


  • I then upgraded to jsreport 3.2 (with a new/empty) postgres DB but for some odd reason almost all the logs are doubled.

    You have configured multiple loggers to transport to the console. This is the default

     "logger": {
        "console": {
          "transport": "console",
          "level": "debug"
        },
        "file": {
          "transport": "file",
          "level": "info",
          "filename": "logs/reporter.log"
        },
        "error": {
          "transport": "file",
          "level": "error",
          "filename": "logs/error.log"
        }
      },
    

    The second issue I am having is about the rendering speed

    Did you measure the speed after multiple requests? Every worker (you have now 2) is lazy compiling code during the first run.



    1. Thanks for pointing that out... It seems I had actually used that same config typo in jsreport 2.11 as well.

    2. Yes, I rendered the template over 10 times with the studio UI.
      And everytime the "Replaced assets" debug lines had almost the same constant time +1500 to +1600 ms.



  • Would it be possible for you to email me your workspace export so I can try to compare the performance?
    jan.blaha@jsreport.net



  • Of couse.
    I sent you the email.



  • Thank you for the export.
    I see the same performance 1.2s on version 3.2.0 as well as 2.11.

    The log starts like this

    info +0 Starting rendering request 5 (user: null)
    info +0 Rendering template { name: tickets-default, recipe: chrome-pdf, engine: handlebars, preview: true }
    debug +7 Adding sample data BMnrDZR
    debug +55 Base url not specified, skipping its injection.
    debug +50 Replaced assets ["../../watermark.css","ltg-styles.css","./../images/ltg_link_logo.svg","./../images/blank_logo.svg","./../images/route_icon.svg","./../images/arrow_icon.svg","./../images/od_icon.svg","./../images/date_icon.svg","./../images/time_icon.svg","./../images/person_icon.svg","./../images/train_icon.svg","./../images/class_icon.svg","./../images/coach_icon.svg","./../images/seat_icon.svg","./../images/wheelchair_icon.svg"]
    debug +49 Replaced assets ["/LTG/fonts/semibold.ttf","/LTG/fonts/regular.ttf"]
    debug +7 Replaced assets ["ltg-helper-functions.js"]
    debug +0 Rendering engine handlebars
    

    Is it not caching the assets and asking the assets from DB every time I render?

    This was never cached, but the db queries are made in parallel.

    So far I don't know what could be the reason. I would recommend trying run jsreport with fs store or memory store and compare the performance. Maybe the postgres for some reason just slowly responds.



  • Yes, it probably has to do with the postgres slowness.
    I downgraded jsreport 3.2 back to jsreport 2.11 on the same kubernetes pod (same machine, same resources, same postgres server) to compare.

    Below are the results.
    But even if the postgres is slow to respond, I wonder why does the asset replacing take 3 times longer now in jsreport 3.2 compared to jsreport 2.11 with the same setup.

    For now, I have to keep using jsreport 2.11.

    +0      Starting rendering request 15 (user: root)
    +71     Rendering template { name: tickets-default, recipe: chrome-pdf, engine: handlebars, preview: true }
    +210    Adding sample data BMnrDZR
    +211    Resources not defined for this template.
    +246    Base url not specified, skipping its injection.
    +592    Replaced assets ["../../watermark.css","./../images/train_icon.svg","test-styles.css","./../images/class_icon.svg","./../images/coach_icon.svg","./../images/seat_icon.svg","./../images/wheelchair_icon.svg","./../images/blank_logo.svg","./../images/route_icon.svg","./../images/od_icon.svg","./../images/arrow_icon.svg","./../images/test_link_logo.svg","./../images/date_icon.svg","./../images/person_icon.svg","./../images/time_icon.svg"]
    +1006   Replaced assets ["/test/fonts/regular.ttf","/test/fonts/semibold.ttf"]
    +1146   Replaced assets ["test-helper-functions.js"]
    +1147   Rendering engine handlebars using http-server strategy
    +1175   Taking compiled template from engine cache
    +1226   Executing recipe chrome-pdf
    +1241   Converting with chrome HeadlessChrome/79.0.3945.130 using chrome-pool strategy
    +1291   Page request: GET (document) file:///tmp/jsreport/autocleanup/18dce46d-5d68-47ca-97af-a4259c7d64fd-chrome-pdf.html
    +1319   Page request finished: GET (document) file:///tmp/jsreport/autocleanup/18dce46d-5d68-47ca-97af-a4259c7d64fd-chrome-pdf.html
    +1331   Page request: GET (image) data:image/svg+xml;base64,PHN2ZyBpZD0iTGF5ZXJfMSIgZGF0YS1uYW1lPSJMYXllciAxIiB4bWxucz0iaHR0cDovL3d3dy...
    +1333   Page request finished: GET (image) 200 data:image/svg+xml;base64,PHN2ZyBpZD0iTGF5ZXJfMSIgZGF0YS1uYW1lPSJMYXllciAxIiB4bWxucz0iaHR0cDovL3d3dy...
    +1333   Page request: GET (image) data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iNTM5IiBoZWlnaHQ9IjM1IiB4bWxucz0iaHR0cDovL3d3dy53My5vcmcvMj...
    +1333   Page request finished: GET (image) 200 data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iNTM5IiBoZWlnaHQ9IjM1IiB4bWxucz0iaHR0cDovL3d3dy53My5vcmcvMj...
    +1334   Page request: GET (image) data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iMTIzIiBoZWlnaHQ9IjE3MCIgeG1sbnM9Imh0dHA6Ly93d3cudzMub3JnLz...
    +1334   Page request finished: GET (image) 200 data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iMTIzIiBoZWlnaHQ9IjE3MCIgeG1sbnM9Imh0dHA6Ly93d3cudzMub3JnLz...
    +1334   Page request: GET (image) data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iMjkiIGhlaWdodD0iMTAiIHhtbG5zPSJodHRwOi8vd3d3LnczLm9yZy8yMD...
    +1335   Page request finished: GET (image) 200 data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iMjkiIGhlaWdodD0iMTAiIHhtbG5zPSJodHRwOi8vd3d3LnczLm9yZy8yMD...
    +1335   Page request: GET (image) data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iNzUiIGhlaWdodD0iNzguNzMiIHhtbG5zPSJodHRwOi8vd3d3LnczLm9yZy...
    +1335   Page request finished: GET (image) 200 data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iNzUiIGhlaWdodD0iNzguNzMiIHhtbG5zPSJodHRwOi8vd3d3LnczLm9yZy...
    +1375   Page request: GET (font) data:font/ttf;base64,AAEAAAAPAIAAAwBwRkZUTYSLY84AAiesAAAAHEdERUYJMQupAAEbCAAAADZHUE9TukgglQABMfAAAPW6R1NVQjF/R20AARtAAAAWrk9TLzJr0XaZAAABeAAAAGBjbWFwgtKRBgAADOQAAAR2Z2FzcP//AAMAARsAAAAACGdseWYsBK6xAAAW5AAA5VxoZWFkEfGAogAAAPwAAAA2aGhlYQfTBbwAAAE0AAAAJGhtdHjOWDcbAAAB2AAACwxsb2NhDjFOIAAAEVwAAAWIbWF4cAPIChYAAAFYAAAAIG5hbWWca0aZAAD8QAAACLVwb3N08mvPcwABBPgAABYHAAEAAAADAELnx3lfXw889QALA+gAAAAA1z2Z4QAAAADXqaLk/o3+zgVbA+gAAQAIAAIAAAAAAAAAAQAAA+j+9AAABXf+jf6NBVsAAQAAAAAAAAAAAAAAAAAAAsMAAQAAAsMKEwDDAAAAAAACAAAAAQABAAAAQAAAAAAAAAADAl0CWAAFAAACvAKKAAAAjAK8AooAAAHdAEkBEwAAAgsHBAAAAAAAAKAAAn9QAAA7AAAAIAAAAABTd1R5ACAAIP7/Atz+9AAAA+gBDCAAAJcAAAAAAfQCxgAAACAADAKEAEgAAAAAAU0AAADkAAABIAArAbIAOgLWACACGAAoA9QAHAMCAD8A6AA6ASsAJwEqABYB7AAfAewAJAEaABsBkAA5ARcAJwIiABICdwAzAXAAAgI9ACkCTAAjAn0AEgI8AB8COQAjAh8AIgJMACoCOQAnASsAMQEuACYB3QAaAh0APAHdABsCBwAcAyMAKALnAAECegBPAwwAJgLMAE8COABPAh4ATwMlACYC9ABPAS8ATwIjABQCtgBPAicATwN0AE8DGABPAygAJQJPAE8DSAAlApAATwJRACICOAAVAtAARwLRAAMDwQASArn/+gKyAA...
    +1387   Page request: GET (image) data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iNzUiIGhlaWdodD0iNzIuOTk5OTk5OTk5OTk5OTkiIHhtbG5zPSJodHRwOi...
    +1388   Page request finished: GET (image) 200 data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iNzUiIGhlaWdodD0iNzIuOTk5OTk5OTk5OTk5OTkiIHhtbG5zPSJodHRwOi...
    +1388   Page request: GET (image) data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iNzUiIGhlaWdodD0iODAuNSIgeG1sbnM9Imh0dHA6Ly93d3cudzMub3JnLz...
    +1388   Page request finished: GET (image) 200 data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iNzUiIGhlaWdodD0iODAuNSIgeG1sbnM9Imh0dHA6Ly93d3cudzMub3JnLz...
    +1389   Page request: GET (image) data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iNzUiIGhlaWdodD0iODYiIHhtbG5zPSJodHRwOi8vd3d3LnczLm9yZy8yMD...
    +1390   Page request finished: GET (image) 200 data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iNzUiIGhlaWdodD0iODYiIHhtbG5zPSJodHRwOi8vd3d3LnczLm9yZy8yMD...
    +1390   Page request: GET (image) data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iNjgiIGhlaWdodD0iNzUiIHhtbG5zPSJodHRwOi8vd3d3LnczLm9yZy8yMD...
    +1390   Page request finished: GET (image) 200 data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iNjgiIGhlaWdodD0iNzUiIHhtbG5zPSJodHRwOi8vd3d3LnczLm9yZy8yMD...
    +1391   Page request: GET (image) data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iNjgiIGhlaWdodD0iNzkiIHhtbG5zPSJodHRwOi8vd3d3LnczLm9yZy8yMD...
    +1391   Page request finished: GET (image) 200 data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iNjgiIGhlaWdodD0iNzkiIHhtbG5zPSJodHRwOi8vd3d3LnczLm9yZy8yMD...
    +1392   Page request: GET (image) data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iOTkiIGhlaWdodD0iNjMiIHhtbG5zPSJodHRwOi8vd3d3LnczLm9yZy8yMD...
    +1392   Page request finished: GET (image) 200 data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iOTkiIGhlaWdodD0iNjMiIHhtbG5zPSJodHRwOi8vd3d3LnczLm9yZy8yMD...
    +1392   Page request: GET (image) data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iNzAiIGhlaWdodD0iODciIHhtbG5zPSJodHRwOi8vd3d3LnczLm9yZy8yMD...
    +1392   Page request finished: GET (image) 200 data:image/svg+xml;base64,PHN2ZyB3aWR0aD0iNzAiIGhlaWdodD0iODciIHhtbG5zPSJodHRwOi8vd3d3LnczLm9yZy8yMD...
    +1393   Page request: GET (image) data:image/png;base64,iVBORw0KGgoAAAANSUhEUgAAAGkAAABpCAYAAAA5gg06AAAAAXNSR0IArs4c6QAAAARnQU1BAACxjw...
    +1393   Page request finished: GET (image) 200 data:image/png;base64,iVBORw0KGgoAAAANSUhEUgAAAGkAAABpCAYAAAA5gg06AAAAAXNSR0IArs4c6QAAAARnQU1BAACxjw...
    +1393   Page request: GET (image) data:image/png;base64,iVBORw0KGgoAAAANSUhEUgAAAtAAAABkCAIAAAAg8ZCBAAAAAXNSR0IArs4c6QAAAARnQU1BAACxjw...
    +1393   Page request finished: GET (image) 200 data:image/png;base64,iVBORw0KGgoAAAANSUhEUgAAAtAAAABkCAIAAAAg8ZCBAAAAAXNSR0IArs4c6QAAAARnQU1BAACxjw...
    +1442   Page request finished: GET (font) 200 data:font/ttf;base64,AAEAAAAPAIAAAwBwRkZUTYSLY84AAiesAAAAHEdERUYJMQupAAEbCAAAADZHUE9TukgglQABMfAAAPW6R1NVQjF/R20AARtAAAAWrk9TLzJr0XaZAAABeAAAAGBjbWFwgtKRBgAADOQAAAR2Z2FzcP//AAMAARsAAAAACGdseWYsBK6xAAAW5AAA5VxoZWFkEfGAogAAAPwAAAA2aGhlYQfTBbwAAAE0AAAAJGhtdHjOWDcbAAAB2AAACwxsb2NhDjFOIAAAEVwAAAWIbWF4cAPIChYAAAFYAAAAIG5hbWWca0aZAAD8QAAACLVwb3N08mvPcwABBPgAABYHAAEAAAADAELnx3lfXw889QALA+gAAAAA1z2Z4QAAAADXqaLk/o3+zgVbA+gAAQAIAAIAAAAAAAAAAQAAA+j+9AAABXf+jf6NBVsAAQAAAAAAAAAAAAAAAAAAAsMAAQAAAsMKEwDDAAAAAAACAAAAAQABAAAAQAAAAAAAAAADAl0CWAAFAAACvAKKAAAAjAK8AooAAAHdAEkBEwAAAgsHBAAAAAAAAKAAAn9QAAA7AAAAIAAAAABTd1R5ACAAIP7/Atz+9AAAA+gBDCAAAJcAAAAAAfQCxgAAACAADAKEAEgAAAAAAU0AAADkAAABIAArAbIAOgLWACACGAAoA9QAHAMCAD8A6AA6ASsAJwEqABYB7AAfAewAJAEaABsBkAA5ARcAJwIiABICdwAzAXAAAgI9ACkCTAAjAn0AEgI8AB8COQAjAh8AIgJMACoCOQAnASsAMQEuACYB3QAaAh0APAHdABsCBwAcAyMAKALnAAECegBPAwwAJgLMAE8COABPAh4ATwMlACYC9ABPAS8ATwIjABQCtgBPAicATwN0AE8DGABPAygAJQJPAE8DSAAlApAATwJRACICOAAVAtAARwLRAAMDw...
    +1447   Running chrome with params {"printBackground":true,"format":"A4","marginTop":"30px","marginBottom":"30px","mediaType":"print","margin":{"top":"30px","bottom":"30px"}}
    +1628   Rendering request 15 finished in 1628 ms
    

Log in to reply
 

Looks like your connection to jsreport forum was lost, please wait while we try to reconnect.