JS Report Docker(ECS) Soft Crashed - jsreport/jsreport:3.6.2
-
JS Report Docker (jsreport/jsreport:3.6.2) via AWS ECS had a soft crash. The CPU shot to 100% and stayed there for about 10 seconds. Memory went up to about 750MB. Neither of these were enough to trigger the orchestrator to restart the container. It appears from the logs the chrome pdf generation process crashed and did not recover. A report generation failed and then no reports would generate after that. Has been working flawlessly for months. We are looking for an easy way to detect the soft crash and trigger a restart of the container. I'm considering making a very simple pdf report and making a curl request as part of the health check. Secondly, if possible I believe it would be better if JSReport hard crashed as this would make it easier for self-healing systems to detect and correct the problem.
I do not have enough privileges to upload so here is the log file.
timestamp,message 1.6782E+12,"2023-03-07T15:19:18.194Z - debug: Running chrome with params {""waitForNetworkIdle"":true,""printBackground"":true,""timeout"":1349,""margin"":{}} rootId=ktvybm0e0dxabfo, id=4of90l4zij3z86t" 1.6782E+12,"2023-03-07T15:19:18.210Z - debug: Skipping storing report. rootId=ktvybm0e0dxabfo, id=4of90l4zij3z86t" 1.6782E+12,"2023-03-07T15:19:18.210Z - info: Rendering request 173474 finished in 59742 ms rootId=ktvybm0e0dxabfo, id=4of90l4zij3z86t" 1.6782E+12,"2023-03-07T15:19:18.210Z - debug: pdf-utils postproces start rootId=ktvybm0e0dxabfo, id=ktvybm0e0dxabfo" 1.6782E+12,"2023-03-07T15:19:18.210Z - debug: pdf-utils postproces end rootId=ktvybm0e0dxabfo, id=ktvybm0e0dxabfo" 1.6782E+12,"2023-03-07T15:19:18.210Z - info: pdf-utils pdf processing was finished rootId=ktvybm0e0dxabfo, id=ktvybm0e0dxabfo" 1.6782E+12,"2023-03-07T15:19:18.210Z - debug: Skipping storing report. rootId=ktvybm0e0dxabfo, id=ktvybm0e0dxabfo" 1.6782E+12,"2023-03-07T15:19:18.210Z - info: Rendering request 173474 finished in 59754 ms rootId=ktvybm0e0dxabfo, id=ktvybm0e0dxabfo" 1.6782E+12,"2023-03-07T15:19:18.246Z - info: Render request 173581 queued for execution and waiting for availible worker rootId=bygtadubx6b7q07, id=bygtadubx6b7q07" 1.6782E+12,"2023-03-07T15:19:18.251Z - info: Starting rendering request 173476 (user: null) rootId=izr9l5cx1b48phn, id=izr9l5cx1b48phn" 1.6782E+12,"2023-03-07T15:19:18.251Z - info: Rendering template { name: radiology-report-prior, recipe: chrome-pdf, engine: handlebars, preview: false } rootId=izr9l5cx1b48phn, id=izr9l5cx1b48phn" 1.6782E+12,"2023-03-07T15:19:18.251Z - debug: Inline data specified. rootId=izr9l5cx1b48phn, id=izr9l5cx1b48phn" 1.6782E+12,"2023-03-07T15:19:18.251Z - debug: Executing script report.js (beforeRender) rootId=izr9l5cx1b48phn, id=izr9l5cx1b48phn" 1.6782E+12,"2023-03-07T15:19:18.289Z - debug: Base url not specified, skipping its injection. rootId=izr9l5cx1b48phn, id=izr9l5cx1b48phn" 1.6782E+12,"2023-03-07T15:19:18.289Z - debug: Rendering engine handlebars rootId=izr9l5cx1b48phn, id=izr9l5cx1b48phn" 1.6782E+12,"2023-03-07T15:19:18.296Z - debug: Pdf utils adding group field, PII REMOVED" 1.6782E+12,"2023-03-07T15:19:18.296Z - debug: Executing recipe chrome-pdf rootId=izr9l5cx1b48phn, id=izr9l5cx1b48phn" 1.6782E+12,"2023-03-07T15:19:18.361Z - info: Render request 173582 queued for execution and waiting for availible worker rootId=eohonvnatt9rd53, id=eohonvnatt9rd53" 1.6782E+12,"2023-03-07T15:19:18.373Z - info: Render request 173583 queued for execution and waiting for availible worker rootId=tun6mp2n3xphsiw, id=tun6mp2n3xphsiw" 1.6782E+12,"2023-03-07T15:19:18.377Z - info: Render request 173584 queued for execution and waiting for availible worker rootId=9yd2kxn9wh2sese, id=9yd2kxn9wh2sese" 1.6782E+12,"2023-03-07T15:19:18.396Z - debug: Converting with chrome HeadlessChrome/93.0.4577.82 using chrome-pool strategy rootId=izr9l5cx1b48phn, id=izr9l5cx1b48phn" 1.6782E+12,"2023-03-07T15:19:18.396Z - debug: Page request: GET (document) file:///tmp/jsreport/autocleanup/d1f8ab72-f2e8-4035-b9bd-74464f03de2c-chrome-pdf.html rootId=izr9l5cx1b48phn, id=izr9l5cx1b48phn" 1.6782E+12,"2023-03-07T15:19:18.396Z - debug: Page request finished: GET (document) file:///tmp/jsreport/autocleanup/d1f8ab72-f2e8-4035-b9bd-74464f03de2c-chrome-pdf.html rootId=izr9l5cx1b48phn, id=izr9l5cx1b48phn" 1.6782E+12,"2023-03-07T15:19:18.396Z - debug: Running chrome with params {""printBackground"":false,""marginTop"":""2cm"",""marginRight"":""1cm"",""marginBottom"":""2cm"",""marginLeft"":""1cm"",""displayHeaderFooter"":false,""waitForNetworkIdle"":false,""timeout"":177,""margin"":{""top"":""2cm"",""right"":""1cm"",""bottom"":""2cm"",""left"":""1cm""}} rootId=izr9l5cx1b48phn, id=izr9l5cx1b48phn" 1.6782E+12,"2023-03-07T15:19:18.400Z - info: Render request 173585 queued for execution and waiting for availible worker rootId=21h35ovsj9bpt8j, id=21h35ovsj9bpt8j" 1.6782E+12,"2023-03-07T15:19:18.437Z - info: Render request 173586 queued for execution and waiting for availible worker rootId=yi1ciwheobvfb03, id=yi1ciwheobvfb03" 1.6782E+12,"2023-03-07T15:19:18.439Z - info: Render request 173587 queued for execution and waiting for availible worker rootId=c374luuse4u7rwo, id=c374luuse4u7rwo" 1.6782E+12,"2023-03-07T15:19:18.440Z - info: Render request 173588 queued for execution and waiting for availible worker rootId=8oyq6j1n03fnvin, id=8oyq6j1n03fnvin" 1.6782E+12,"2023-03-07T15:19:18.441Z - info: Render request 173589 queued for execution and waiting for availible worker rootId=u4v03ixvlmsk8a1, id=u4v03ixvlmsk8a1" 1.6782E+12,"2023-03-07T15:19:18.442Z - info: Render request 173590 queued for execution and waiting for availible worker rootId=1ucmh43ujjiyau5, id=1ucmh43ujjiyau5" 1.6782E+12,"2023-03-07T15:19:18.453Z - info: Render request 173591 queued for execution and waiting for availible worker rootId=y78d9mh6mo5a7zp, id=y78d9mh6mo5a7zp" 1.6782E+12,"2023-03-07T15:19:18.454Z - info: pdf-utils is starting pdf processing rootId=izr9l5cx1b48phn, id=izr9l5cx1b48phn" 1.6782E+12,"2023-03-07T15:19:18.454Z - debug: pdf-utils detected 1 pdf operation(s) to process rootId=izr9l5cx1b48phn, id=izr9l5cx1b48phn" 1.6782E+12,"2023-03-07T15:19:18.454Z - debug: pdf-utils running pdf operation merge rootId=izr9l5cx1b48phn, id=izr9l5cx1b48phn" 1.6782E+12,"2023-03-07T15:19:18.454Z - info: Starting rendering request 173476 (user: null) rootId=izr9l5cx1b48phn, id=6yqamru2up4jv16" 1.6782E+12,"2023-03-07T15:19:18.454Z - info: Rendering template { name: header-footer-prior, recipe: chrome-pdf, engine: handlebars, preview: false } rootId=izr9l5cx1b48phn, id=6yqamru2up4jv16" 1.6782E+12,"2023-03-07T15:19:18.454Z - debug: Inline data specified. rootId=izr9l5cx1b48phn, id=6yqamru2up4jv16" 1.6782E+12,"2023-03-07T15:19:18.455Z - debug: Base url not specified, skipping its injection. rootId=izr9l5cx1b48phn, id=6yqamru2up4jv16" 1.6782E+12,"2023-03-07T15:19:18.455Z - debug: Rendering engine handlebars rootId=izr9l5cx1b48phn, id=6yqamru2up4jv16" 1.6782E+12,"2023-03-07T15:19:18.455Z - info: Render request 173592 queued for execution and waiting for availible worker rootId=rjhlnyxj1g8chzq, id=rjhlnyxj1g8chzq" 1.6782E+12,2023-03-07T15:19:18.462Z - error: Error when processing render request 173475 Timeout Error: chrome pdf generation not completed after 651ms Error: Timeout Error: chrome pdf generation not completed after 651ms 1.6782E+12, at Timeout._onTimeout (/app/node_modules/@jsreport/jsreport-chrome-pdf/lib/conversion.js:389:21) 1.6782E+12, at listOnTimeout (node:internal/timers:557:17) 1.6782E+12," at processTimers (node:internal/timers:500:7) rootId=fahi9y3bhjem2bf, id=zzsjv2i5ji9o2cw" 1.6782E+12,"2023-03-07T15:19:18.462Z - error: Rendering request 173475 finished with error in 60002 ms rootId=fahi9y3bhjem2bf, id=zzsjv2i5ji9o2cw" 1.6782E+12,2023-03-07T15:19:18.463Z - warn: Error when processing render request 173475 Error while executing pdf-utils operations. Timeout Error: chrome pdf generation not completed after 651ms Error: Error while executing pdf-utils operations. Timeout Error: chrome pdf generation not completed after 651ms 1.6782E+12, at module.exports (/app/node_modules/@jsreport/jsreport-core/lib/shared/createError.js:10:13) 1.6782E+12, at WorkerReporter.createError (/app/node_modules/@jsreport/jsreport-core/lib/shared/reporter.js:47:12) 1.6782E+12, at AsyncFunction.<anonymous> (/app/node_modules/@jsreport/jsreport-pdf-utils/lib/worker.js:191:22) 1.6782E+12, at async ListenerCollection.fire (/app/node_modules/@jsreport/jsreport-core/lib/shared/listenerCollection.js:157:21) 1.6782E+12, at async afterRender (/app/node_modules/@jsreport/jsreport-core/lib/worker/render/render.js:103:5) 1.6782E+12, at async WorkerReporter._render (/app/node_modules/@jsreport/jsreport-core/lib/worker/render/render.js:148:7) 1.6782E+12, at async /app/node_modules/@jsreport/jsreport-core/lib/worker/reporter.js:179:19 1.6782E+12, at async Domain.<anonymous> (/app/node_modules/@jsreport/advanced-workers/lib/workerHandler.js:141:19) 1.6782E+12,caused by: Error: Timeout Error: chrome pdf generation not completed after 651ms 1.6782E+12, at Timeout._onTimeout (/app/node_modules/@jsreport/jsreport-chrome-pdf/lib/conversion.js:389:21) 1.6782E+12, at listOnTimeout (node:internal/timers:557:17) 1.6782E+12," at processTimers (node:internal/timers:500:7) rootId=fahi9y3bhjem2bf, id=fahi9y3bhjem2bf" 1.6782E+12,"2023-03-07T15:19:18.463Z - warn: Rendering request 173475 finished with error in 60003 ms rootId=fahi9y3bhjem2bf, id=fahi9y3bhjem2bf" 1.6782E+12,2023-03-07T15:19:18.468Z - warn: Report render failed: Report cancelled Error: Report cancelled 1.6782E+12, at Object.close (/app/node_modules/@jsreport/advanced-workers/lib/threadWorker.js:147:21) 1.6782E+12, at Object.release (/app/node_modules/@jsreport/advanced-workers/lib/pool.js:38:22) 1.6782E+12, at EventEmitter.<anonymous> (/app/node_modules/@jsreport/jsreport-core/lib/main/reporter.js:397:18) 1.6782E+12, at Object.onceWrapper (node:events:509:28) 1.6782E+12, at EventEmitter.emit (node:events:390:28) 1.6782E+12, at Socket.<anonymous> (/app/node_modules/@jsreport/jsreport-express/lib/routes.js:68:20) 1.6782E+12, at Object.onceWrapper (node:events:510:26) 1.6782E+12, at Socket.emit (node:events:402:35) 1.6782E+12," at TCP.<anonymous> (node:net:687:12) rootId=fahi9y3bhjem2bf, id=fahi9y3bhjem2bf" 1.6782E+12,"2023-03-07T15:19:18.470Z - warn: Error during processing request at http://internal-cfecs-lb8a1-c23llcmbfx5m-1825856027.us-gov-west-1.elb.amazonaws.com/api/report, details: Report cancelled, stack: Error: Report cancelled" 1.6782E+12, at Object.close (/app/node_modules/@jsreport/advanced-workers/lib/threadWorker.js:147:21) 1.6782E+12, at Object.release (/app/node_modules/@jsreport/advanced-workers/lib/pool.js:38:22) 1.6782E+12, at EventEmitter.<anonymous> (/app/node_modules/@jsreport/jsreport-core/lib/main/reporter.js:397:18) 1.6782E+12, at Object.onceWrapper (node:events:509:28) 1.6782E+12, at EventEmitter.emit (node:events:390:28) 1.6782E+12, at Socket.<anonymous> (/app/node_modules/@jsreport/jsreport-express/lib/routes.js:68:20) 1.6782E+12, at Object.onceWrapper (node:events:510:26) 1.6782E+12, at Socket.emit (node:events:402:35) 1.6782E+12, at TCP.<anonymous> (node:net:687:12) 1.6782E+12,2023-03-07T15:19:18.477Z - warn: Report render failed: Report cancelled Error: Report cancelled 1.6782E+12, at Object.close (/app/node_modules/@jsreport/advanced-workers/lib/threadWorker.js:147:21) 1.6782E+12, at Object.release (/app/node_modules/@jsreport/advanced-workers/lib/pool.js:38:22) 1.6782E+12, at EventEmitter.<anonymous> (/app/node_modules/@jsreport/jsreport-core/lib/main/reporter.js:397:18) 1.6782E+12, at Object.onceWrapper (node:events:509:28) 1.6782E+12, at EventEmitter.emit (node:events:390:28) 1.6782E+12, at Socket.<anonymous> (/app/node_modules/@jsreport/jsreport-express/lib/routes.js:68:20) 1.6782E+12, at Object.onceWrapper (node:events:510:26) 1.6782E+12, at Socket.emit (node:events:402:35) 1.6782E+12," at TCP.<anonymous> (node:net:687:12) rootId=izr9l5cx1b48phn, id=izr9l5cx1b48phn" 1.6782E+12,"2023-03-07T15:19:18.482Z - warn: Error during processing request at http://internal-cfecs-lb8a1-c23llcmbfx5m-1825856027.us-gov-west-1.elb.amazonaws.com/api/report, details: Report cancelled, stack: Error: Report cancelled" 1.6782E+12, at Object.close (/app/node_modules/@jsreport/advanced-workers/lib/threadWorker.js:147:21) 1.6782E+12, at Object.release (/app/node_modules/@jsreport/advanced-workers/lib/pool.js:38:22) 1.6782E+12, at EventEmitter.<anonymous> (/app/node_modules/@jsreport/jsreport-core/lib/main/reporter.js:397:18) 1.6782E+12, at Object.onceWrapper (node:events:509:28) 1.6782E+12, at EventEmitter.emit (node:events:390:28) 1.6782E+12, at Socket.<anonymous> (/app/node_modules/@jsreport/jsreport-express/lib/routes.js:68:20) 1.6782E+12, at Object.onceWrapper (node:events:510:26) 1.6782E+12, at Socket.emit (node:events:402:35) 1.6782E+12, at TCP.<anonymous> (node:net:687:12) 1.6782E+12,2023-03-07T15:19:18.483Z - error: Report render failed: Timeout when waiting for worker Error: Timeout when waiting for worker 1.6782E+12, at Timeout._onTimeout (/app/node_modules/@jsreport/advanced-workers/lib/pool.js:78:27) 1.6782E+12, at listOnTimeout (node:internal/timers:557:17) 1.6782E+12," at processTimers (node:internal/timers:500:7) rootId=ijtxv6ul3j4lir8, id=ijtxv6ul3j4lir8" 1.6782E+12,"2023-03-07T15:19:18.484Z - error: Error during processing request at http://internal-cfecs-lb8a1-c23llcmbfx5m-1825856027.us-gov-west-1.elb.amazonaws.com/api/report, details: Cannot read properties of undefined (reading 'reports'), stack: TypeError: Cannot read properties of undefined (reading 'reports')" 1.6782E+12, at Reports._handleRenderError (/app/node_modules/@jsreport/jsreport-reports/lib/main.js:223:25) 1.6782E+12, at ListenerCollection.fire (/app/node_modules/@jsreport/jsreport-core/lib/shared/listenerCollection.js:157:32) 1.6782E+12, at async MainReporter._handleRenderError (/app/node_modules/@jsreport/jsreport-core/lib/main/reporter.js:355:5) 1.6782E+12, at async MainReporter.render (/app/node_modules/@jsreport/jsreport-core/lib/main/reporter.js:479:7) 1.6782E+12,"2023-03-07T15:19:18.487Z - info: Render request 173593 queued for execution and waiting for availible worker rootId=0uwgp3q8488psbu, id=0uwgp3q8488psbu" 1.6782E+12,"2023-03-07T15:19:18.504Z - info: Render request 173594 queued for execution and waiting for availible worker rootId=7j3jd9uw1yv8oby, id=7j3jd9uw1yv8oby" 1.6782E+12,"2023-03-07T15:19:18.509Z - info: Render request 173595 queued for execution and waiting for availible worker rootId=50dwm8npa9e7ab0, id=50dwm8npa9e7ab0" 1.6782E+12,"2023-03-07T15:19:18.518Z - info: Render request 173596 queued for execution and waiting for availible worker rootId=t9ud6j06hkguvqf, id=t9ud6j06hkguvqf" 1.6782E+12,"2023-03-07T15:19:20.001Z - info: Starting rendering request 173478 (user: null) rootId=enh19ie219ovudx, id=enh19ie219ovudx" 1.6782E+12,"2023-03-07T15:19:20.001Z - info: Rendering template { name: radiology-report-prior, recipe: chrome-pdf, engine: handlebars, preview: false } rootId=enh19ie219ovudx, id=enh19ie219ovudx" 1.6782E+12,"2023-03-07T15:19:20.001Z - debug: Inline data specified. rootId=enh19ie219ovudx, id=enh19ie219ovudx" 1.6782E+12,"2023-03-07T15:19:20.002Z - debug: Executing script report.js (beforeRender) rootId=enh19ie219ovudx, id=enh19ie219ovudx" 1.6782E+12,"2023-03-07T15:19:20.044Z - info: Starting rendering request 173479 (user: null) rootId=lmummv84iimu4tk, id=lmummv84iimu4tk" 1.6782E+12,"2023-03-07T15:19:20.044Z - info: Rendering template { name: radiology-report-prior, recipe: chrome-pdf, engine: handlebars, preview: false } rootId=lmummv84iimu4tk, id=lmummv84iimu4tk" 1.6782E+12,"2023-03-07T15:19:20.044Z - debug: Inline data specified. rootId=lmummv84iimu4tk, id=lmummv84iimu4tk" 1.6782E+12,"2023-03-07T15:19:20.045Z - debug: Executing script report.js (beforeRender) rootId=lmummv84iimu4tk, id=lmummv84iimu4tk" 1.6782E+12,2023-03-07T15:19:20.313Z - debug: req { 1.6782E+12,"2023-03-07T15:19:20.393Z - debug: Base url not specified, skipping its injection. rootId=lmummv84iimu4tk, id=lmummv84iimu4tk" 1.6782E+12,"2023-03-07T15:19:20.393Z - debug: Rendering engine handlebars rootId=lmummv84iimu4tk, id=lmummv84iimu4tk" 1.6782E+12,2023-03-07T15:19:20.397Z - warn: Report render failed: Report cancelled Error: Report cancelled 1.6782E+12, at Object.close (/app/node_modules/@jsreport/advanced-workers/lib/threadWorker.js:147:21) 1.6782E+12, at Object.release (/app/node_modules/@jsreport/advanced-workers/lib/pool.js:38:22) 1.6782E+12, at EventEmitter.<anonymous> (/app/node_modules/@jsreport/jsreport-core/lib/main/reporter.js:397:18) 1.6782E+12, at Object.onceWrapper (node:events:509:28) 1.6782E+12, at EventEmitter.emit (node:events:390:28) 1.6782E+12, at Socket.<anonymous> (/app/node_modules/@jsreport/jsreport-express/lib/routes.js:68:20) 1.6782E+12, at Object.onceWrapper (node:events:510:26) 1.6782E+12, at Socket.emit (node:events:402:35) 1.6782E+12," at TCP.<anonymous> (node:net:687:12) rootId=enh19ie219ovudx, id=enh19ie219ovudx" 1.6782E+12,"2023-03-07T15:19:20.399Z - warn: Error during processing request at http://internal-cfecs-lb8a1-c23llcmbfx5m-1825856027.us-gov-west-1.elb.amazonaws.com/api/report, details: Report cancelled, stack: Error: Report cancelled" 1.6782E+12, at Object.close (/app/node_modules/@jsreport/advanced-workers/lib/threadWorker.js:147:21) 1.6782E+12, at Object.release (/app/node_modules/@jsreport/advanced-workers/lib/pool.js:38:22) 1.6782E+12, at EventEmitter.<anonymous> (/app/node_modules/@jsreport/jsreport-core/lib/main/reporter.js:397:18) 1.6782E+12, at Object.onceWrapper (node:events:509:28) 1.6782E+12, at EventEmitter.emit (node:events:390:28) 1.6782E+12, at Socket.<anonymous> (/app/node_modules/@jsreport/jsreport-express/lib/routes.js:68:20) 1.6782E+12, at Object.onceWrapper (node:events:510:26) 1.6782E+12, at Socket.emit (node:events:402:35) 1.6782E+12, at TCP.<anonymous> (node:net:687:12) 1.6782E+12,"2023-03-07T15:19:20.796Z - info: Render request 173597 queued for execution and waiting for availible worker rootId=me92pnudlq3gj12, id=me92pnudlq3gj12" 1.6782E+12,"2023-03-07T15:19:20.797Z - info: Render request 173598 queued for execution and waiting for availible worker rootId=6l93od4xe9n1lzb, id=6l93od4xe9n1lzb" 1.6782E+12,2023-03-07T15:19:20.863Z - warn: Report render failed: Report cancelled Error: Report cancelled 1.6782E+12, at Object.close (/app/node_modules/@jsreport/advanced-workers/lib/threadWorker.js:147:21) 1.6782E+12, at Object.release (/app/node_modules/@jsreport/advanced-workers/lib/pool.js:38:22) 1.6782E+12, at EventEmitter.<anonymous> (/app/node_modules/@jsreport/jsreport-core/lib/main/reporter.js:397:18) 1.6782E+12, at Object.onceWrapper (node:events:509:28) 1.6782E+12, at EventEmitter.emit (node:events:390:28) 1.6782E+12, at Socket.<anonymous> (/app/node_modules/@jsreport/jsreport-express/lib/routes.js:68:20) 1.6782E+12, at Object.onceWrapper (node:events:510:26) 1.6782E+12, at Socket.emit (node:events:402:35) 1.6782E+12," at TCP.<anonymous> (node:net:687:12) rootId=lmummv84iimu4tk, id=lmummv84iimu4tk" 1.6782E+12,"2023-03-07T15:19:20.864Z - warn: Error during processing request at http://internal-cfecs-lb8a1-c23llcmbfx5m-1825856027.us-gov-west-1.elb.amazonaws.com/api/report, details: Report cancelled, stack: Error: Report cancelled" 1.6782E+12, at Object.close (/app/node_modules/@jsreport/advanced-workers/lib/threadWorker.js:147:21) 1.6782E+12, at Object.release (/app/node_modules/@jsreport/advanced-workers/lib/pool.js:38:22) 1.6782E+12, at EventEmitter.<anonymous> (/app/node_modules/@jsreport/jsreport-core/lib/main/reporter.js:397:18) 1.6782E+12, at Object.onceWrapper (node:events:509:28) 1.6782E+12, at EventEmitter.emit (node:events:390:28) 1.6782E+12, at Socket.<anonymous> (/app/node_modules/@jsreport/jsreport-express/lib/routes.js:68:20) 1.6782E+12, at Object.onceWrapper (node:events:510:26) 1.6782E+12, at Socket.emit (node:events:402:35) 1.6782E+12, at TCP.<anonymous> (node:net:687:12) 1.6782E+12,"2023-03-07T15:19:21.083Z - info: Starting rendering request 173480 (user: null) rootId=0efi1tgpci5cfi6, id=0efi1tgpci5cfi6" 1.6782E+12,"2023-03-07T15:19:21.083Z - info: Rendering template { name: radiology-report-prior, recipe: chrome-pdf, engine: handlebars, preview: false } rootId=0efi1tgpci5cfi6, id=0efi1tgpci5cfi6" 1.6782E+12,"2023-03-07T15:19:21.083Z - debug: Inline data specified. rootId=0efi1tgpci5cfi6, id=0efi1tgpci5cfi6" 1.6782E+12,"2023-03-07T15:19:21.083Z - debug: Executing script report.js (beforeRender) rootId=0efi1tgpci5cfi6, id=0efi1tgpci5cfi6" 1.6782E+12,"2023-03-07T15:19:21.218Z - info: Starting rendering request 173481 (user: null) rootId=sargeitthzkh56e, id=sargeitthzkh56e" 1.6782E+12,"2023-03-07T15:19:21.219Z - info: Rendering template { name: radiology-report-prior, recipe: chrome-pdf, engine: handlebars, preview: false } rootId=sargeitthzkh56e, id=sargeitthzkh56e" 1.6782E+12,"2023-03-07T15:19:21.219Z - debug: Inline data specified. rootId=sargeitthzkh56e, id=sargeitthzkh56e" 1.6782E+12,"2023-03-07T15:19:21.219Z - debug: Executing script report.js (beforeRender) rootId=sargeitthzkh56e, id=sargeitthzkh56e" 1.6782E+12,2023-03-07T15:19:21.299Z - warn: Report render failed: Report cancelled Error: Report cancelled 1.6782E+12, at Object.close (/app/node_modules/@jsreport/advanced-workers/lib/threadWorker.js:147:21) 1.6782E+12, at Object.release (/app/node_modules/@jsreport/advanced-workers/lib/pool.js:38:22) 1.6782E+12, at EventEmitter.<anonymous> (/app/node_modules/@jsreport/jsreport-core/lib/main/reporter.js:397:18) 1.6782E+12, at Object.onceWrapper (node:events:509:28) 1.6782E+12, at EventEmitter.emit (node:events:390:28) 1.6782E+12, at Socket.<anonymous> (/app/node_modules/@jsreport/jsreport-express/lib/routes.js:68:20) 1.6782E+12, at Object.onceWrapper (node:events:510:26) 1.6782E+12, at Socket.emit (node:events:402:35) 1.6782E+12," at TCP.<anonymous> (node:net:687:12) rootId=0efi1tgpci5cfi6, id=0efi1tgpci5cfi6" 1.6782E+12,"2023-03-07T15:19:21.299Z - warn: Error during processing request at http://internal-cfecs-lb8a1-c23llcmbfx5m-1825856027.us-gov-west-1.elb.amazonaws.com/api/report, details: Report cancelled, stack: Error: Report cancelled" 1.6782E+12, at Object.close (/app/node_modules/@jsreport/advanced-workers/lib/threadWorker.js:147:21) 1.6782E+12, at Object.release (/app/node_modules/@jsreport/advanced-workers/lib/pool.js:38:22) 1.6782E+12, at EventEmitter.<anonymous> (/app/node_modules/@jsreport/jsreport-core/lib/main/reporter.js:397:18) 1.6782E+12, at Object.onceWrapper (node:events:509:28) 1.6782E+12, at EventEmitter.emit (node:events:390:28) 1.6782E+12, at Socket.<anonymous> (/app/node_modules/@jsreport/jsreport-express/lib/routes.js:68:20) 1.6782E+12, at Object.onceWrapper (node:events:510:26) 1.6782E+12, at Socket.emit (node:events:402:35) 1.6782E+12, at TCP.<anonymous> (node:net:687:12) 1.6782E+12,"2023-03-07T15:19:21.580Z - debug: Base url not specified, skipping its injection. rootId=sargeitthzkh56e, id=sargeitthzkh56e" 1.6782E+12,"2023-03-07T15:19:21.580Z - debug: Rendering engine handlebars rootId=sargeitthzkh56e, id=sargeitthzkh56e" 1.6782E+12,"2023-03-07T15:19:21.597Z - info: Starting rendering request 173482 (user: null) rootId=cu7sfzrdafk7qka, id=cu7sfzrdafk7qka" 1.6782E+12,"2023-03-07T15:19:21.598Z - info: Rendering template { name: radiology-report-prior, recipe: chrome-pdf, engine: handlebars, preview: false } rootId=cu7sfzrdafk7qka, id=cu7sfzrdafk7qka" 1.6782E+12,"2023-03-07T15:19:21.598Z - debug: Inline data specified. rootId=cu7sfzrdafk7qka, id=cu7sfzrdafk7qka" 1.6782E+12,"2023-03-07T15:19:21.598Z - debug: Executing script report.js (beforeRender) rootId=cu7sfzrdafk7qka, id=cu7sfzrdafk7qka" 1.6782E+12,"2023-03-07T15:19:22.008Z - debug: Base url not specified, skipping its injection. rootId=cu7sfzrdafk7qka, id=cu7sfzrdafk7qka" 1.6782E+12,"2023-03-07T15:19:22.008Z - debug: Rendering engine handlebars rootId=cu7sfzrdafk7qka, id=cu7sfzrdafk7qka" 1.6782E+12,"2023-03-07T15:19:22.059Z - debug: Pdf utils adding group field, value: PII REMOVED" 1.6782E+12,"2023-03-07T15:19:22.060Z - debug: Executing recipe chrome-pdf rootId=sargeitthzkh56e, id=sargeitthzkh56e" 1.6782E+12,2023-03-07T15:19:22.202Z - warn: Report render failed: Report cancelled Error: Report cancelled 1.6782E+12, at Object.close (/app/node_modules/@jsreport/advanced-workers/lib/threadWorker.js:147:21) 1.6782E+12, at Object.release (/app/node_modules/@jsreport/advanced-workers/lib/pool.js:38:22) 1.6782E+12, at EventEmitter.<anonymous> (/app/node_modules/@jsreport/jsreport-core/lib/main/reporter.js:397:18) 1.6782E+12, at Object.onceWrapper (node:events:509:28) 1.6782E+12, at EventEmitter.emit (node:events:390:28) 1.6782E+12, at Socket.<anonymous> (/app/node_modules/@jsreport/jsreport-express/lib/routes.js:68:20) 1.6782E+12, at Object.onceWrapper (node:events:510:26) 1.6782E+12, at Socket.emit (node:events:402:35) 1.6782E+12," at TCP.<anonymous> (node:net:687:12) rootId=sargeitthzkh56e, id=sargeitthzkh56e" 1.6782E+12,"2023-03-07T15:19:22.203Z - warn: Error during processing request at http://internal-cfecs-lb8a1-c23llcmbfx5m-1825856027.us-gov-west-1.elb.amazonaws.com/api/report, details: Report cancelled, stack: Error: Report cancelled" 1.6782E+12, at Object.close (/app/node_modules/@jsreport/advanced-workers/lib/threadWorker.js:147:21) 1.6782E+12, at Object.release (/app/node_modules/@jsreport/advanced-workers/lib/pool.js:38:22) 1.6782E+12, at EventEmitter.<anonymous> (/app/node_modules/@jsreport/jsreport-core/lib/main/reporter.js:397:18) 1.6782E+12, at Object.onceWrapper (node:events:509:28) 1.6782E+12, at EventEmitter.emit (node:events:390:28) 1.6782E+12, at Socket.<anonymous> (/app/node_modules/@jsreport/jsreport-express/lib/routes.js:68:20) 1.6782E+12, at Object.onceWrapper (node:events:510:26) 1.6782E+12, at Socket.emit (node:events:402:35) 1.6782E+12, at TCP.<anonymous> (node:net:687:12) 1.6782E+12,"2023-03-07T15:19:22.530Z - info: Starting rendering request 173483 (user: null) rootId=a5kdkyse72v4ip4, id=a5kdkyse72v4ip4" 1.6782E+12,"2023-03-07T15:19:22.531Z - info: Rendering template { name: radiology-report-prior, recipe: chrome-pdf, engine: handlebars, preview: false } rootId=a5kdkyse72v4ip4, id=a5kdkyse72v4ip4" 1.6782E+12,"2023-03-07T15:19:22.531Z - debug: Inline data specified. rootId=a5kdkyse72v4ip4, id=a5kdkyse72v4ip4" 1.6782E+12,"2023-03-07T15:19:22.531Z - debug: Executing script report.js (beforeRender) rootId=a5kdkyse72v4ip4, id=a5kdkyse72v4ip4" 1.6782E+12,"2023-03-07T15:19:22.593Z - debug: Pdf utils adding group field, value: PII REMOVED" 1.6782E+12,"2023-03-07T15:19:22.593Z - debug: Executing recipe chrome-pdf rootId=cu7sfzrdafk7qka, id=cu7sfzrdafk7qka" 1.6782E+12,2023-03-07T15:19:22.611Z - warn: Report render failed: Report cancelled Error: Report cancelled 1.6782E+12, at Object.close (/app/node_modules/@jsreport/advanced-workers/lib/threadWorker.js:147:21) 1.6782E+12, at Object.release (/app/node_modules/@jsreport/advanced-workers/lib/pool.js:38:22) 1.6782E+12, at EventEmitter.<anonymous> (/app/node_modules/@jsreport/jsreport-core/lib/main/reporter.js:397:18) 1.6782E+12, at Object.onceWrapper (node:events:509:28) 1.6782E+12, at EventEmitter.emit (node:events:390:28) 1.6782E+12, at Socket.<anonymous> (/app/node_modules/@jsreport/jsreport-express/lib/routes.js:68:20) 1.6782E+12, at Object.onceWrapper (node:events:510:26) 1.6782E+12, at Socket.emit (node:events:402:35) 1.6782E+12," at TCP.<anonymous> (node:net:687:12) rootId=cu7sfzrdafk7qka, id=cu7sfzrdafk7qka" 1.6782E+12,"2023-03-07T15:19:22.612Z - warn: Error during processing request at http://internal-cfecs-lb8a1-c23llcmbfx5m-1825856027.us-gov-west-1.elb.amazonaws.com/api/report, details: Report cancelled, stack: Error: Report cancelled" 1.6782E+12, at Object.close (/app/node_modules/@jsreport/advanced-workers/lib/threadWorker.js:147:21) 1.6782E+12, at Object.release (/app/node_modules/@jsreport/advanced-workers/lib/pool.js:38:22) 1.6782E+12, at EventEmitter.<anonymous> (/app/node_modules/@jsreport/jsreport-core/lib/main/reporter.js:397:18) 1.6782E+12, at Object.onceWrapper (node:events:509:28) 1.6782E+12, at EventEmitter.emit (node:events:390:28) 1.6782E+12, at Socket.<anonymous> (/app/node_modules/@jsreport/jsreport-express/lib/routes.js:68:20) 1.6782E+12, at Object.onceWrapper (node:events:510:26) 1.6782E+12, at Socket.emit (node:events:402:35) 1.6782E+12, at TCP.<anonymous> (node:net:687:12)
-
Thank you for submitting your observations.
This was the error you were observing for all the later requests until you rebooted jsreport?
2023-03-07T15:19:21.299Z - warn: Report render failed: Report cancelled Error: Report cancelledBecause this error signals the client closed the socket connection. In this case, jsreport stops execution.
-
Sorry, I forgot to check back in. Yes, that was the error we were observing. We have never seen that before. The error went away as soon as we forced the container to stop and the orchestrator started a new container the problem went away.
