rendering a report of large data



  • Hi,

    I am trying to render jsreport with around 100k records (pdf using phantom-pdf and also tried with chrome-pdf) from my application using jsreport client. It is taking longer time to generate. In the meantime, session gets timeout in my application and hence report not getting rendered/downloaded in the site.

    Is there a way to improve the performance and avoid session timeouts.

    This is very critical for my site. Please help me to get this done.



  • Please share the jsreport logs for particular rendering so we can see the timing where it spends the most of the time.



  • 2018-05-30T17:04:38.323Z - info: Initializing jsreport@2.0.0 in development mode using configuration file: jsreport.config.json
    2018-05-30T17:04:38.329Z - info: Searching for available extensions in F:\ReportingServerJS
    2018-05-30T17:04:38.345Z - info: Extensions location cache not found, crawling directories
    2018-05-30T17:04:42.080Z - info: Found 31 extensions
    2018-05-30T17:04:42.144Z - debug: Writing extension locations cache to C:\Users\CHALAP~1.SIR\AppData\Local\Temp\jsreport\core\locations.json
    2018-05-30T17:04:42.149Z - debug: Discovered 31 extensions
    2018-05-30T17:04:42.304Z - info: Setting process based strategy for rendering. Please visit http://jsreport.net/learn/configuration for information how to get more performance.
    2018-05-30T17:04:42.331Z - info: Using extension handlebars
    2018-05-30T17:04:42.358Z - info: Using extension import-export
    2018-05-30T17:04:42.832Z - info: Using extension templates
    2018-05-30T17:04:42.849Z - info: Using extension jsrender
    2018-05-30T17:04:42.852Z - debug: Extension authentication is disabled, skipping
    2018-05-30T17:04:42.853Z - info: Using extension freeze
    2018-05-30T17:04:42.860Z - info: Using extension cli
    2018-05-30T17:04:42.864Z - info: Using extension express
    2018-05-30T17:04:43.392Z - info: Using extension debug
    2018-05-30T17:04:43.400Z - info: Using extension tags
    2018-05-30T17:04:43.414Z - info: Using extension data
    2018-05-30T17:04:43.424Z - info: Using extension fs-store
    2018-05-30T17:04:44.263Z - info: Using extension authorization
    2018-05-30T17:04:44.272Z - debug: Extension authorization was disabled
    2018-05-30T17:04:44.273Z - info: Using extension html-to-xlsx
    2018-05-30T17:04:44.823Z - info: html-to-xlsx detected chrome as available html engine
    2018-05-30T17:04:44.825Z - info: Using extension child-templates
    2018-05-30T17:04:44.836Z - info: Using extension browser-client
    2018-05-30T17:04:44.847Z - info: Using extension chrome-pdf
    2018-05-30T17:04:44.860Z - info: Using extension pdf-utils
    2018-05-30T17:04:45.352Z - info: Using extension version-control
    2018-05-30T17:04:45.490Z - info: Using extension reports
    2018-05-30T17:04:45.500Z - info: Using extension text
    2018-05-30T17:04:45.504Z - info: Using extension base
    2018-05-30T17:04:45.513Z - info: Using extension studio
    2018-05-30T17:04:45.639Z - info: Using extension licensing
    2018-05-30T17:04:45.725Z - info: Using extension scripts
    2018-05-30T17:04:45.744Z - info: Using extension assets
    2018-05-30T17:04:45.807Z - info: Using extension scheduling
    2018-05-30T17:04:45.957Z - info: Using extension xlsx
    2018-05-30T17:04:46.074Z - info: Using extension sample-template
    2018-05-30T17:04:46.082Z - info: Using extension resources
    2018-05-30T17:04:46.088Z - info: Using extension public-templates
    2018-05-30T17:04:46.095Z - debug: Extension public-templates was disabled
    2018-05-30T17:04:46.114Z - info: fs store is persisting using fs
    2018-05-30T17:04:46.117Z - info: fs store is synchronizing using fs
    2018-05-30T17:04:46.296Z - info: fs store is loading data
    2018-05-30T17:04:46.425Z - info: fs store is initialized successfully
    2018-05-30T17:04:46.500Z - info: Creating default express app.
    2018-05-30T17:04:46.605Z - info: jsreport server successfully started on http port: 5488
    2018-05-30T17:04:46.607Z - info: fs store emits sockets to synchronize underlying changes with studio
    2018-05-30T17:04:46.757Z - info: Verifying license key free
    2018-05-30T17:04:46.763Z - info: Using free license
    2018-05-30T17:04:46.773Z - debug: Inserting samples
    2018-05-30T17:04:46.806Z - debug: Inserting sample entity Population
    2018-05-30T17:04:46.881Z - debug: Inserting sample entity Invoice
    2018-05-30T17:04:46.927Z - debug: Inserting sample entity Population
    2018-05-30T17:04:46.988Z - debug: Inserting sample entity OrdersHeader
    2018-05-30T17:04:47.035Z - debug: Inserting sample entity Invoice
    2018-05-30T17:04:47.097Z - debug: Inserting sample entity Orders
    2018-05-30T17:04:47.152Z - debug: Inserting sample entity Invoice styles.css
    2018-05-30T17:04:47.222Z - debug: Inserting sample entity global helpers.js
    2018-05-30T17:04:47.311Z - debug: Inserting sample entity Invoice logo.png
    2018-05-30T17:04:47.367Z - debug: Inserting sample entity Orders
    2018-05-30T17:04:47.421Z - debug: Inserting sample entity Population
    2018-05-30T17:04:47.802Z - info: reporter initialized
    2018-05-30T17:05:03.623Z - debug: OData query on templates
    2018-05-30T17:05:03.626Z - debug: OData query on tags
    2018-05-30T17:05:03.645Z - debug: OData query on scripts
    2018-05-30T17:05:03.649Z - debug: OData query on data
    2018-05-30T17:05:03.652Z - debug: OData query on assets
    2018-05-30T17:05:03.655Z - debug: OData query on xlsxTemplates
    2018-05-30T17:05:03.673Z - debug: OData query on schedules
    2018-05-30T17:05:03.692Z - debug: OData query on settings
    2018-05-30T17:05:03.862Z - debug: OData query on templates
    2018-05-30T17:05:03.880Z - debug: OData query on settings
    2018-05-30T17:05:06.637Z - debug: OData query on templates
    2018-05-30T17:05:06.676Z - debug: OData query on templates
    2018-05-30T17:05:07.562Z - debug: OData query on settings
    2018-05-30T17:08:49.386Z - info: Initializing jsreport@2.0.0 in development mode using configuration file: jsreport.config.json
    2018-05-30T17:08:49.391Z - info: Searching for available extensions in F:\ReportingServerJS
    2018-05-30T17:08:49.405Z - info: Extensions location cache C:\Users\CHALAP~1.SIR\AppData\Local\Temp\jsreport\core\locations.json contains older information, crawling
    2018-05-30T17:08:50.949Z - info: Found 32 extensions
    2018-05-30T17:08:50.984Z - debug: Writing extension locations cache to C:\Users\CHALAP~1.SIR\AppData\Local\Temp\jsreport\core\locations.json
    2018-05-30T17:08:50.990Z - debug: Discovered 32 extensions
    2018-05-30T17:08:51.074Z - info: Setting process based strategy for rendering. Please visit http://jsreport.net/learn/configuration for information how to get more performance.
    2018-05-30T17:08:51.086Z - info: Using extension handlebars
    2018-05-30T17:08:51.096Z - info: Using extension jsrender
    2018-05-30T17:08:51.099Z - info: Using extension templates
    2018-05-30T17:08:51.108Z - debug: Extension authentication is disabled, skipping
    2018-05-30T17:08:51.110Z - info: Using extension import-export
    2018-05-30T17:08:51.289Z - info: Using extension freeze
    2018-05-30T17:08:51.292Z - info: Using extension cli
    2018-05-30T17:08:51.294Z - info: Using extension express
    2018-05-30T17:08:51.485Z - info: Using extension debug
    2018-05-30T17:08:51.488Z - info: Using extension tags
    2018-05-30T17:08:51.496Z - info: Using extension data
    2018-05-30T17:08:51.500Z - info: Using extension fs-store
    2018-05-30T17:08:51.795Z - info: Using extension authorization
    2018-05-30T17:08:51.798Z - debug: Extension authorization was disabled
    2018-05-30T17:08:51.799Z - info: Using extension html-to-xlsx
    2018-05-30T17:08:51.983Z - info: html-to-xlsx detected chrome as available html engine
    2018-05-30T17:08:51.984Z - info: html-to-xlsx detected phantom as available html engine
    2018-05-30T17:08:51.985Z - info: Using extension child-templates
    2018-05-30T17:08:51.990Z - info: Using extension browser-client
    2018-05-30T17:08:51.994Z - info: Using extension licensing
    2018-05-30T17:08:52.022Z - info: Using extension chrome-pdf
    2018-05-30T17:08:52.027Z - info: Using extension phantom-pdf
    2018-05-30T17:08:52.074Z - info: Using extension version-control
    2018-05-30T17:08:52.116Z - info: Using extension reports
    2018-05-30T17:08:52.120Z - info: Using extension text
    2018-05-30T17:08:52.122Z - info: Using extension base
    2018-05-30T17:08:52.124Z - info: Using extension studio
    2018-05-30T17:08:52.168Z - info: Using extension pdf-utils
    2018-05-30T17:08:52.439Z - info: Using extension scripts
    2018-05-30T17:08:52.458Z - info: Using extension assets
    2018-05-30T17:08:52.504Z - info: Using extension scheduling
    2018-05-30T17:08:52.633Z - info: Using extension xlsx
    2018-05-30T17:08:52.737Z - info: Using extension sample-template
    2018-05-30T17:08:52.745Z - info: Using extension resources
    2018-05-30T17:08:52.752Z - info: Using extension public-templates
    2018-05-30T17:08:52.758Z - debug: Extension public-templates was disabled
    2018-05-30T17:08:52.773Z - info: fs store is persisting using fs
    2018-05-30T17:08:52.776Z - info: fs store is synchronizing using fs
    2018-05-30T17:08:52.945Z - info: fs store is loading data
    2018-05-30T17:08:53.164Z - info: fs store is initialized successfully
    2018-05-30T17:08:53.229Z - info: Creating default express app.
    2018-05-30T17:08:53.303Z - info: jsreport server successfully started on http port: 5488
    2018-05-30T17:08:53.307Z - info: fs store emits sockets to synchronize underlying changes with studio
    2018-05-30T17:08:53.343Z - info: Verifying license key free
    2018-05-30T17:08:53.349Z - info: Using free license
    2018-05-30T17:08:53.365Z - info: reporter initialized
    2018-05-30T17:09:48.050Z - debug: OData query on templates
    2018-05-30T17:09:48.103Z - debug: OData query on tags
    2018-05-30T17:09:48.107Z - debug: OData query on data
    2018-05-30T17:09:48.113Z - debug: OData query on scripts
    2018-05-30T17:09:48.118Z - debug: OData query on assets
    2018-05-30T17:09:48.122Z - debug: OData query on xlsxTemplates
    2018-05-30T17:09:48.181Z - debug: OData query on schedules
    2018-05-30T17:09:48.216Z - debug: OData query on settings
    2018-05-30T17:09:48.569Z - debug: OData query on templates
    2018-05-30T17:09:58.193Z - debug: OData query on tags
    2018-05-30T17:09:58.218Z - debug: OData query on templates
    2018-05-30T17:09:58.225Z - debug: OData query on data
    2018-05-30T17:09:58.238Z - debug: OData query on scripts
    2018-05-30T17:09:58.244Z - debug: OData query on assets
    2018-05-30T17:09:58.249Z - debug: OData query on xlsxTemplates
    2018-05-30T17:09:58.296Z - debug: OData query on schedules
    2018-05-30T17:09:58.334Z - debug: OData query on settings
    2018-05-30T17:09:58.601Z - debug: OData query on templates
    2018-05-30T17:09:58.691Z - debug: OData query on settings
    2018-05-30T17:10:00.565Z - debug: OData query on templates
    2018-05-30T17:10:00.607Z - debug: OData query on templates
    2018-05-30T17:10:01.315Z - debug: OData query on settings
    2018-05-30T17:11:41.321Z - info: Initializing jsreport@2.0.0 in development mode using configuration file: dev.config.json
    2018-05-30T17:11:41.326Z - info: Searching for available extensions in F:\ReportingServerJS
    2018-05-30T17:11:41.342Z - info: Extensions location cache contains up to date information, skipping crawling in F:\ReportingServerJS\node_modules
    2018-05-30T17:11:41.356Z - info: Found 32 extensions
    2018-05-30T17:11:41.392Z - debug: Writing extension locations cache to C:\Users\CHALAP~1.SIR\AppData\Local\Temp\jsreport\core\locations.json
    2018-05-30T17:11:41.399Z - debug: Discovered 32 extensions
    2018-05-30T17:11:44.125Z - info: Using extension handlebars
    2018-05-30T17:11:44.133Z - info: Using extension jsrender
    2018-05-30T17:11:44.137Z - info: Using extension templates
    2018-05-30T17:11:44.144Z - debug: Extension authentication is disabled, skipping
    2018-05-30T17:11:44.145Z - info: Using extension import-export
    2018-05-30T17:11:44.322Z - info: Using extension freeze
    2018-05-30T17:11:44.325Z - info: Using extension cli
    2018-05-30T17:11:44.327Z - info: Using extension express
    2018-05-30T17:11:44.476Z - info: Using extension debug
    2018-05-30T17:11:44.480Z - info: Using extension tags
    2018-05-30T17:11:44.484Z - info: Using extension data
    2018-05-30T17:11:44.489Z - info: Using extension fs-store
    2018-05-30T17:11:44.743Z - info: Using extension authorization
    2018-05-30T17:11:44.746Z - debug: Extension authorization was disabled
    2018-05-30T17:11:44.747Z - info: Using extension html-to-xlsx
    2018-05-30T17:11:44.920Z - info: html-to-xlsx detected chrome as available html engine
    2018-05-30T17:11:44.922Z - info: html-to-xlsx detected phantom as available html engine
    2018-05-30T17:11:44.923Z - info: Using extension child-templates
    2018-05-30T17:11:44.928Z - info: Using extension browser-client
    2018-05-30T17:11:44.932Z - info: Using extension licensing
    2018-05-30T17:11:44.960Z - info: Using extension chrome-pdf
    2018-05-30T17:11:44.965Z - info: Using extension phantom-pdf
    2018-05-30T17:11:45.009Z - info: Using extension version-control
    2018-05-30T17:11:45.055Z - info: Using extension reports
    2018-05-30T17:11:45.059Z - info: Using extension text
    2018-05-30T17:11:45.061Z - info: Using extension base
    2018-05-30T17:11:45.064Z - info: Using extension studio
    2018-05-30T17:11:45.109Z - info: Using extension pdf-utils
    2018-05-30T17:11:45.250Z - info: Using extension scripts
    2018-05-30T17:11:45.256Z - info: Using extension assets
    2018-05-30T17:11:45.269Z - info: Using extension scheduling
    2018-05-30T17:11:45.311Z - info: Using extension xlsx
    2018-05-30T17:11:45.340Z - info: Using extension sample-template
    2018-05-30T17:11:45.344Z - info: Using extension resources
    2018-05-30T17:11:45.346Z - info: Using extension public-templates
    2018-05-30T17:11:45.348Z - debug: Extension public-templates was disabled
    2018-05-30T17:11:45.356Z - info: fs store is persisting using fs
    2018-05-30T17:11:45.358Z - info: fs store is synchronizing using fs
    2018-05-30T17:11:45.429Z - info: fs store is loading data
    2018-05-30T17:11:45.516Z - info: fs store is initialized successfully
    2018-05-30T17:11:45.539Z - info: Creating default express app.
    2018-05-30T17:11:45.562Z - info: jsreport server successfully started on http port: 5488
    2018-05-30T17:11:45.563Z - info: fs store emits sockets to synchronize underlying changes with studio
    2018-05-30T17:11:45.576Z - info: Verifying license key
    2018-05-30T17:11:46.339Z - info: License key verified as yearly subscription
    2018-05-30T17:11:46.341Z - info: Storing license verification information to jsreport.license.json
    2018-05-30T17:11:46.361Z - info: reporter initialized
    2018-05-30T17:12:02.722Z - debug: OData query on templates
    2018-05-30T17:12:02.749Z - debug: OData query on tags
    2018-05-30T17:12:02.755Z - debug: OData query on data
    2018-05-30T17:12:02.761Z - debug: OData query on scripts
    2018-05-30T17:12:02.764Z - debug: OData query on assets
    2018-05-30T17:12:02.776Z - debug: OData query on xlsxTemplates
    2018-05-30T17:12:02.813Z - debug: OData query on schedules
    2018-05-30T17:12:02.863Z - debug: OData query on settings
    2018-05-30T17:12:03.376Z - debug: OData query on templates
    2018-05-30T17:12:03.446Z - debug: OData query on settings
    2018-05-30T17:12:09.404Z - debug: OData query on templates
    2018-05-30T17:12:09.423Z - debug: OData query on templates
    2018-05-30T17:12:10.456Z - debug: OData query on settings
    2018-05-30T17:12:31.553Z - debug: OData query on scripts
    2018-05-30T17:13:25.317Z - debug: fs store sync is triggering reload, because F:\ReportingServerJS\data\data\ScheduledAppointments was changed by other process
    2018-05-30T17:13:25.345Z - info: fs store is loading data
    2018-05-30T17:13:25.391Z - debug: fs store sync is triggering reload, because F:\ReportingServerJS\data\data\activityData was changed by other process
    2018-05-30T17:13:25.951Z - info: fs store is loading data
    2018-05-30T17:13:25.962Z - debug: Sending external-modification socket to the studio
    2018-05-30T17:13:26.191Z - debug: OData query on templates
    2018-05-30T17:13:26.254Z - debug: Sending external-modification socket to the studio
    2018-05-30T17:13:26.858Z - info: Starting rendering request 1 (user: null)
    2018-05-30T17:13:26.867Z - info: Rendering template { name: Orders, recipe: chrome-pdf, engine: handlebars, preview: true }
    2018-05-30T17:13:26.871Z - debug: Data item not defined for this template.
    2018-05-30T17:13:26.874Z - debug: Resources not defined for this template.
    2018-05-30T17:13:26.886Z - debug: Executing script Orders
    2018-05-30T17:13:27.482Z - warn: Error when processing render request require of "http" module has been blocked. To be able to require custom modules you need to add to configuration { "allowLocalFilesAccess": true } or enable just specific module using { "extensions": { "scripts": { "allowedModules": ["http"] } } Error: require of "http" module has been blocked. To be able to require custom modules you need to add to configuration { "allowLocalFilesAccess": true } or enable just specific module using { "extensions": { "scripts": { "allowedModules": ["http"] } }
    at _require (F:\ReportingServerJS\node_modules\jsreport-core\lib\render\safeSandbox.js:59:19)
    at Object.apply (F:\ReportingServerJS\node_modules\vm2\lib/contextify.js:288:34)
    at vm.js:2:14
    at ContextifyScript.Script.runInContext (vm.js:59:29)
    at VM.run (F:\ReportingServerJS\node_modules\vm2\lib\main.js:212:72)
    at module.exports (F:\ReportingServerJS\node_modules\jsreport-scripts\lib\scriptEvalChild.js:125:3)
    at IncomingMessage.<anonymous> (F:\ReportingServerJS\node_modules\script-manager\lib\worker-servers.js:239:47)
    at emitNone (events.js:106:13)
    at IncomingMessage.emit (events.js:208:7)
    at endReadableNT (_stream_readable.js:1064:12)
    at _combinedTickCallback (internal/process/next_tick.js:138:11)
    at process._tickDomainCallback (internal/process/next_tick.js:218:9)
    2018-05-30T17:13:27.486Z - warn: Error during processing request at http://localhost:5488/api/report/Orders
    2018-05-30T17:13:43.948Z - debug: fs store sync is triggering reload, because F:\ReportingServerJS\data\templates\ScheduledAppointmentsxlsx was changed by other process
    2018-05-30T17:13:43.949Z - info: fs store is loading data
    2018-05-30T17:13:44.578Z - debug: Sending external-modification socket to the studio
    2018-05-30T17:13:44.719Z - debug: OData query on templates
    2018-05-30T17:13:45.255Z - info: Starting rendering request 2 (user: null)
    2018-05-30T17:13:45.270Z - info: Rendering template { name: Orders, recipe: chrome-pdf, engine: handlebars, preview: true }
    2018-05-30T17:13:45.273Z - debug: Data item not defined for this template.
    2018-05-30T17:13:45.276Z - debug: Resources not defined for this template.
    2018-05-30T17:13:45.287Z - debug: Executing script Orders
    2018-05-30T17:13:45.368Z - warn: Error when processing render request require of "http" module has been blocked. To be able to require custom modules you need to add to configuration { "allowLocalFilesAccess": true } or enable just specific module using { "extensions": { "scripts": { "allowedModules": ["http"] } } Error: require of "http" module has been blocked. To be able to require custom modules you need to add to configuration { "allowLocalFilesAccess": true } or enable just specific module using { "extensions": { "scripts": { "allowedModules": ["http"] } }
    at _require (F:\ReportingServerJS\node_modules\jsreport-core\lib\render\safeSandbox.js:59:19)
    at Object.apply (F:\ReportingServerJS\node_modules\vm2\lib/contextify.js:288:34)
    at vm.js:2:14
    at ContextifyScript.Script.runInContext (vm.js:59:29)
    at VM.run (F:\ReportingServerJS\node_modules\vm2\lib\main.js:212:72)
    at module.exports (F:\ReportingServerJS\node_modules\jsreport-scripts\lib\scriptEvalChild.js:125:3)
    at IncomingMessage.<anonymous> (F:\ReportingServerJS\node_modules\script-manager\lib\worker-servers.js:239:47)
    at emitNone (events.js:106:13)
    at IncomingMessage.emit (events.js:208:7)
    at endReadableNT (_stream_readable.js:1064:12)
    at _combinedTickCallback (internal/process/next_tick.js:138:11)
    at process._tickDomainCallback (internal/process/next_tick.js:218:9)
    2018-05-30T17:13:45.370Z - warn: Error during processing request at http://localhost:5488/api/report/Orders
    2018-05-30T17:13:57.622Z - debug: fs store sync is triggering reload, because F:\ReportingServerJS\data\xlsxTemplates\ScheduledAppointments was changed by other process
    2018-05-30T17:13:57.642Z - info: fs store is loading data
    2018-05-30T17:13:57.987Z - debug: Sending external-modification socket to the studio
    2018-05-30T17:13:58.215Z - debug: OData query on templates
    2018-05-30T17:13:58.670Z - info: Starting rendering request 3 (user: null)
    2018-05-30T17:13:58.675Z - info: Rendering template { name: Orders, recipe: chrome-pdf, engine: handlebars, preview: true }
    2018-05-30T17:13:58.680Z - debug: Data item not defined for this template.
    2018-05-30T17:13:58.686Z - debug: Resources not defined for this template.
    2018-05-30T17:13:58.691Z - debug: Executing script Orders
    2018-05-30T17:13:58.755Z - warn: Error when processing render request require of "http" module has been blocked. To be able to require custom modules you need to add to configuration { "allowLocalFilesAccess": true } or enable just specific module using { "extensions": { "scripts": { "allowedModules": ["http"] } } Error: require of "http" module has been blocked. To be able to require custom modules you need to add to configuration { "allowLocalFilesAccess": true } or enable just specific module using { "extensions": { "scripts": { "allowedModules": ["http"] } }
    at _require (F:\ReportingServerJS\node_modules\jsreport-core\lib\render\safeSandbox.js:59:19)
    at Object.apply (F:\ReportingServerJS\node_modules\vm2\lib/contextify.js:288:34)
    at vm.js:2:14
    at ContextifyScript.Script.runInContext (vm.js:59:29)
    at VM.run (F:\ReportingServerJS\node_modules\vm2\lib\main.js:212:72)
    at module.exports (F:\ReportingServerJS\node_modules\jsreport-scripts\lib\scriptEvalChild.js:125:3)
    at IncomingMessage.<anonymous> (F:\ReportingServerJS\node_modules\script-manager\lib\worker-servers.js:239:47)
    at emitNone (events.js:106:13)
    at IncomingMessage.emit (events.js:208:7)
    at endReadableNT (_stream_readable.js:1064:12)
    at _combinedTickCallback (internal/process/next_tick.js:138:11)
    at process._tickDomainCallback (internal/process/next_tick.js:218:9)
    2018-05-30T17:13:58.757Z - warn: Error during processing request at http://localhost:5488/api/report/Orders
    2018-05-30T17:14:59.080Z - info: Initializing jsreport@2.0.0 in development mode using configuration file: dev.config.json
    2018-05-30T17:14:59.086Z - info: Searching for available extensions in F:\ReportingServerJS
    2018-05-30T17:14:59.114Z - info: Extensions location cache contains up to date information, skipping crawling in F:\ReportingServerJS\node_modules
    2018-05-30T17:14:59.167Z - info: Found 32 extensions
    2018-05-30T17:14:59.225Z - debug: Writing extension locations cache to C:\Users\CHALAP~1.SIR\AppData\Local\Temp\jsreport\core\locations.json
    2018-05-30T17:14:59.238Z - debug: Discovered 32 extensions
    2018-05-30T17:15:04.934Z - info: Using extension handlebars
    2018-05-30T17:15:04.946Z - info: Using extension jsrender
    2018-05-30T17:15:04.948Z - info: Using extension templates
    2018-05-30T17:15:04.959Z - debug: Extension authentication is disabled, skipping
    2018-05-30T17:15:04.960Z - info: Using extension import-export
    2018-05-30T17:15:05.231Z - info: Using extension freeze
    2018-05-30T17:15:05.235Z - info: Using extension cli
    2018-05-30T17:15:05.237Z - info: Using extension express
    2018-05-30T17:15:05.489Z - info: Using extension debug
    2018-05-30T17:15:05.493Z - info: Using extension tags
    2018-05-30T17:15:05.500Z - info: Using extension data
    2018-05-30T17:15:05.505Z - info: Using extension fs-store
    2018-05-30T17:15:05.979Z - info: Using extension authorization
    2018-05-30T17:15:05.987Z - debug: Extension authorization was disabled
    2018-05-30T17:15:05.989Z - info: Using extension html-to-xlsx
    2018-05-30T17:15:06.485Z - info: html-to-xlsx detected chrome as available html engine
    2018-05-30T17:15:06.490Z - info: html-to-xlsx detected phantom as available html engine
    2018-05-30T17:15:06.492Z - info: Using extension child-templates
    2018-05-30T17:15:06.502Z - info: Using extension browser-client
    2018-05-30T17:15:06.510Z - info: Using extension licensing
    2018-05-30T17:15:06.591Z - info: Using extension chrome-pdf
    2018-05-30T17:15:06.605Z - info: Using extension phantom-pdf
    2018-05-30T17:15:06.764Z - info: Using extension version-control
    2018-05-30T17:15:06.890Z - info: Using extension reports
    2018-05-30T17:15:06.899Z - info: Using extension text
    2018-05-30T17:15:06.902Z - info: Using extension base
    2018-05-30T17:15:06.908Z - info: Using extension studio
    2018-05-30T17:15:07.037Z - info: Using extension pdf-utils
    2018-05-30T17:15:07.444Z - info: Using extension scripts
    2018-05-30T17:15:07.461Z - info: Using extension assets
    2018-05-30T17:15:07.507Z - info: Using extension scheduling
    2018-05-30T17:15:07.656Z - info: Using extension xlsx
    2018-05-30T17:15:07.768Z - info: Using extension sample-template
    2018-05-30T17:15:07.775Z - info: Using extension resources
    2018-05-30T17:15:07.780Z - info: Using extension public-templates
    2018-05-30T17:15:07.786Z - debug: Extension public-templates was disabled
    2018-05-30T17:15:07.801Z - info: fs store is persisting using fs
    2018-05-30T17:15:07.803Z - info: fs store is synchronizing using fs
    2018-05-30T17:15:07.966Z - info: fs store is loading data
    2018-05-30T17:15:08.359Z - info: fs store is initialized successfully
    2018-05-30T17:15:08.435Z - info: Creating default express app.
    2018-05-30T17:15:08.515Z - info: jsreport server successfully started on http port: 5488
    2018-05-30T17:15:08.520Z - info: fs store emits sockets to synchronize underlying changes with studio
    2018-05-30T17:15:08.557Z - info: Verifying license key
    2018-05-30T17:15:08.564Z - info: License key for subscription verified against the jsreport.license.json file
    2018-05-30T17:15:08.577Z - info: reporter initialized
    2018-05-30T17:15:18.347Z - debug: OData query on templates
    2018-05-30T17:15:18.383Z - debug: OData query on tags
    2018-05-30T17:15:18.388Z - debug: OData query on data
    2018-05-30T17:15:18.392Z - debug: OData query on scripts
    2018-05-30T17:15:18.398Z - debug: OData query on assets
    2018-05-30T17:15:18.403Z - debug: OData query on xlsxTemplates
    2018-05-30T17:15:18.434Z - debug: OData query on schedules
    2018-05-30T17:15:18.507Z - debug: OData query on settings
    2018-05-30T17:15:19.051Z - debug: OData query on templates
    2018-05-30T17:15:19.103Z - debug: OData query on settings
    2018-05-30T17:15:26.312Z - debug: OData query on templates
    2018-05-30T17:15:26.335Z - debug: OData query on templates
    2018-05-30T17:15:27.386Z - debug: OData query on settings
    2018-05-30T17:15:32.788Z - info: Starting rendering request 1 (user: null)
    2018-05-30T17:15:32.820Z - info: Rendering template { name: ScheduledAppointmentspdf, recipe: phantom-pdf, engine: handlebars, preview: true }
    2018-05-30T17:15:32.825Z - debug: Adding sample data r1JqqYHJQ
    2018-05-30T17:15:32.926Z - debug: Resources not defined for this template.
    2018-05-30T17:15:32.943Z - debug: Base url not specified, skipping its injection.
    2018-05-30T17:15:32.950Z - debug: Rendering engine handlebars
    2018-05-30T17:15:34.710Z - debug: Compiled template not found in the cache, compiling
    2018-05-30T17:15:35.217Z - debug: Executing recipe phantom-pdf
    2018-05-30T17:15:57.744Z - debug: Converting in dedicated phantomjs 1.9.8
    2018-05-30T17:15:57.746Z - debug: Request file:///C%3A%5CUsers%5CCHALAP~1.SIR%5CAppData%5CLocal%5CTemp%5Cjsreport%5Cautocleanup%5C5687de6d-5826-43c1-aee9-fbab130d7c33html.html
    2018-05-30T17:15:58.115Z - debug: phantom-pdf recipe finished with 218 pages generated
    2018-05-30T17:15:58.120Z - debug: Skipping storing report.
    2018-05-30T17:15:58.146Z - info: Rendering request 1 finished in 25394 ms
    2018-05-30T17:16:24.063Z - debug: OData update on templates
    2018-05-30T17:18:09.207Z - info: Starting rendering request 2 (user: null)
    2018-05-30T17:18:09.213Z - info: Rendering template { name: ScheduledAppointmentspdf, recipe: phantom-pdf, engine: handlebars, preview: false }
    2018-05-30T17:18:09.214Z - debug: Inline data specified.
    2018-05-30T17:18:09.215Z - debug: Resources not defined for this template.
    2018-05-30T17:18:09.219Z - debug: Base url not specified, skipping its injection.
    2018-05-30T17:18:09.220Z - debug: Rendering engine handlebars
    2018-05-30T17:18:12.684Z - debug: Compiled template not found in the cache, compiling
    2018-05-30T17:18:13.801Z - debug: Executing recipe phantom-pdf
    2018-05-30T17:20:35.384Z - debug: Converting in dedicated phantomjs 1.9.8
    2018-05-30T17:20:35.384Z - debug: Request file:///C%3A%5CUsers%5CCHALAP~1.SIR%5CAppData%5CLocal%5CTemp%5Cjsreport%5Cautocleanup%5Cfd5e8402-ea08-4bee-a09b-8cbda068fa65html.html
    2018-05-30T17:20:41.077Z - debug: phantom-pdf recipe finished with 2318 pages generated
    2018-05-30T17:20:41.292Z - debug: Skipping storing report.
    2018-05-30T17:20:41.341Z - info: Rendering request 2 finished in 152132 ms



  • Thanks.
    I see you render 2318 pages and jsreport spends the most of the time in phantomjs process during converting html to pdf.
    There isn't much to do. You can try to simplify your css styles but the rendering of such long pdf will always take a while.

    I recommend you to re-architect your UX during rendering such big pdf.
    Make the action a background job.


Log in to reply
 

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