Intermittent Reports Engine Crash When Using Advanced Distribution (Doc ID 859121.1)

Last updated on MARCH 06, 2016

Applies to:

Oracle Reports Developer - Version 10.1.2.0.2 and later
Information in this document applies to any platform.
***Checked for relevance on 16-AUG-2012***

Symptoms

Running a report using Advanced Distribution feature and using variables in the XML distribution file causes the Reports Engine to crash in an intermittent way.

Example:

rwclient.sh server=<Reports Server Name> report=<Report Name>.rdf userid=usr/pwd@db mode=bitmap destination=<Distribution XML file>.xml distribute=yes copies=1 blankpages=no

The above rwclient.sh command sometimes works and generates the correct output, but it sometimes fails and displays the following error:

REP-0177: Error while running in remote server
Engine rwEng-0 crashed, job Id: 868

An example of the used Distribution XML file is as below:

<?xml version="1.0" encoding="ISO-8859-1" ?>
<destinations>
<file
id="D1"
name="<Report Output File Name>.PDF"
format="pdf"
instance="all">
<include src="report"/>
</file>
<foreach>
<file
id="D2"
format="pdf"
name="&variable_name.PDF"
instance="this">
<include src="report"/>
</file>
</foreach>
</destinations>


Enabling the Reports Trace recorded the following trace information:

rwserver.trc

Trace information when it works
~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
[2009/7/15 2:23:22:62] Info 50132 (JobObject:reset): jobid = 867 Get command line: server='<Reports Server Name>' report='<Report Name>' userid='<user/*****@database>' mode='bitmap' destination='<Distribution XML file>.xml' distribute='yes' copies='1' blankpages='no' authid=oracle
[2009/7/15 2:23:22:66] Debug 50103 (ConnectionImpl:runJob): Job queue for jobid = 867 is 0
[2009/7/15 2:23:22:66] Debug 50103 (ConnectionImpl:runJob): jobid = 867 is in current queue
[2009/7/15 2:23:22:66] Debug 50103 (ConnectionImpl:runJob): Calling findDuplicatedJob for jobid = 867
[2009/7/15 2:23:22:67] Debug 50103 (JobManager:findDuplicatedJob): Found no duplicated job for job 867
[2009/7/15 2:23:22:67] Debug 50103 (ConnectionImpl:runJob): No Duplicate jobs for jobid = 867
[2009/7/15 2:23:22:67] Debug 50103 (ConnectionImpl:runJob): Job 867 is Enqueued
[2009/7/15 2:23:22:67] Debug 50103 (JobManager:firstToRun): job 867 is first to run
[2009/7/15 2:23:22:67] Debug 50103 (JobManager.runJobLocal): Trying to get engine for Job 867
[2009/7/15 2:23:22:67] Debug 50103 (EngineManager:getIdleEngine): Target max engines = 2
[2009/7/15 2:23:22:67] Debug 50103 (EngineManager:getIdleEngine): rwEng-0 is used = true
[2009/7/15 2:23:22:67] Debug 50103 (EngineManager:getIdleEngine): rwEng-0 state is 1
[2009/7/15 2:23:22:67] State 56004 (EngineInfo:setState): Engine rwEng-0 state is: Reserved
[2009/7/15 2:23:22:67] Debug 50103 (JobManager.runJobLocal): Job 867 got Engine rwEng-0
[2009/7/15 2:23:22:67] Debug 50103 (JobManager:runJobInEngine): Job 867 calling setCommand on engine rwEng-0
[2009/7/15 2:23:22:69] Debug 50103 (EngineManager:updateEngineState): Engine rwEng-0 status is 3
[2009/7/15 2:23:22:70] State 56004 (EngineInfo:setState): Engine rwEng-0 state is: Running
[2009/7/15 2:23:22:77] Debug 50103 (EngineManager:updateEngineState): Engine rwEng-0 status is 5
[2009/7/15 2:23:22:77] State 56004 (EngineInfo:setState): Engine rwEng-0 state is: Idle
[2009/7/15 2:23:22:78] Debug 50103 (JobManager:runJobInEngine): Send job 867 to engine rwEng-0
[2009/7/15 2:23:22:79] Debug 50103 (EngineManager:updateEngineState): Engine rwEng-0 status is 3
[2009/7/15 2:23:22:79] State 56004 (EngineInfo:setState): Engine rwEng-0 state is: Running
[2009/7/15 2:23:22:954] State 56016 (JobManager:updateJobStatus): Job 867 status is: Running the report Initializing report
[2009/7/15 2:23:22:954] Debug 50103 (JobManager:updateJobStatus): Finished updating job: 867
[2009/7/15 2:23:22:966] State 56016 (JobManager:updateJobStatus): Job 867 status is: Running the report Formatting page 1
[2009/7/15 2:23:22:966] Debug 50103 (JobManager:updateJobStatus): Finished updating job: 867
.
.
====================
Trace information when it doesn't work
~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
[2009/7/15 2:23:32:758] Info 50132 (JobObject:reset): jobid = 868 Get command line: server='<Reports Server Name>' report='<Report Name>' userid='<user/*****@database>' mode='bitmap' destination='<Distribution XML file>.xml' distribute='yes' copies='1' blankpages='no' authid=oracle
[2009/7/15 2:23:32:759] Debug 50103 (ConnectionImpl:runJob): Job queue for jobid = 868 is 0
[2009/7/15 2:23:32:759] Debug 50103 (ConnectionImpl:runJob): jobid = 868 is in current queue
[2009/7/15 2:23:32:759] Debug 50103 (ConnectionImpl:runJob): Calling findDuplicatedJob for jobid = 868
[2009/7/15 2:23:32:759] Debug 50103 (JobManager:findDuplicatedJob): Found no duplicated job for job 868
[2009/7/15 2:23:32:759] Debug 50103 (ConnectionImpl:runJob): No Duplicate jobs for jobid = 868
[2009/7/15 2:23:32:759] Debug 50103 (ConnectionImpl:runJob): Job 868 is Enqueued
[2009/7/15 2:23:32:759] Debug 50103 (JobManager:firstToRun): job 868 is first to run
[2009/7/15 2:23:32:759] Debug 50103 (JobManager.runJobLocal): Trying to get engine for Job 868
[2009/7/15 2:23:32:760] Debug 50103 (EngineManager:getIdleEngine): Target max engines = 2
[2009/7/15 2:23:32:760] Debug 50103 (EngineManager:getIdleEngine): rwEng-0 is used = true
[2009/7/15 2:23:32:760] Debug 50103 (EngineManager:getIdleEngine): rwEng-0 state is 1
[2009/7/15 2:23:32:760] State 56004 (EngineInfo:setState): Engine rwEng-0 state is: Reserved
[2009/7/15 2:23:32:760] Debug 50103 (JobManager.runJobLocal): Job 868 got Engine rwEng-0
[2009/7/15 2:23:32:760] Debug 50103 (JobManager:runJobInEngine): Job 868 calling setCommand on engine rwEng-0
[2009/7/15 2:23:32:762] Debug 50103 (EngineManager:updateEngineState): Engine rwEng-0 status is 3
[2009/7/15 2:23:32:762] State 56004 (EngineInfo:setState): Engine rwEng-0 state is: Running
[2009/7/15 2:23:32:780] Debug 50103 (EngineManager:updateEngineState): Engine rwEng-0 status is 5
[2009/7/15 2:23:32:780] State 56004 (EngineInfo:setState): Engine rwEng-0 state is: Idle
[2009/7/15 2:23:32:781] Debug 50103 (JobManager:runJobInEngine): Send job 868 to engine rwEng-0
[2009/7/15 2:23:32:782] Debug 50103 (EngineManager:updateEngineState): Engine rwEng-0 status is 3
[2009/7/15 2:23:32:782] State 56004 (EngineInfo:setState): Engine rwEng-0 state is: Running
[2009/7/15 2:23:32:828] State 56016 (JobManager:updateJobStatus): Job 868 status is: Running the report Initializing report
[2009/7/15 2:23:32:828] Debug 50103 (JobManager:updateJobStatus): Finished updating job: 868
[2009/7/15 2:23:32:852] Exception 50125 (org.omg.CORBA.COMM_FAILURE: vmcid: SUN minor code: 208 completed: Maybe
at com.sun.corba.se.internal.iiop.IIOPConnection.purge_calls(IIOPConnection.java:438)
at com.sun.corba.se.internal.iiop.ReaderThread.run(ReaderThread.java:70)
): Internal error org.omg.CORBA.COMM_FAILURE: vmcid: SUN minor code: 208 completed: Maybe
[2009/7/15 2:23:32:852] Info 56029 (EngineManager:shutdownEngine): Shutting down engine rwEng-0
[2009/7/15 2:23:32:853] Exception 50125 (org.omg.CORBA.COMM_FAILURE: vmcid: SUN minor code: 201 completed: No
at com.sun.corba.se.internal.iiop.ConnectionTable.getConnection(ConnectionTable.java:148)
at com.sun.corba.se.internal.iiop.ConnectionTable.getConnection(ConnectionTable.java:65)
at com.sun.corba.se.internal.iiop.GIOPImpl.getConnection(GIOPImpl.java:67)
at com.sun.corba.se.internal.corba.ClientDelegate.createRequest(ClientDelegate.java:652)
at com.sun.corba.se.internal.corba.ClientDelegate.createRequest(ClientDelegate.java:594)
at com.sun.corba.se.internal.corba.ClientDelegate.request(ClientDelegate.java:886)
at org.omg.CORBA.portable.ObjectImpl._request(ObjectImpl.java:431)
at oracle.reports.engine._EngineClassStub.shutdown(_EngineClassStub.java:173)
at oracle.reports.server.EngineManager.shutdownEngine(EngineManager.java:1473)
at oracle.reports.server.JobManager.runJobInEngine(JobManager.java:1033)
at oracle.reports.server.JobManager.runJobLocal(JobManager.java:1912)
at oracle.reports.server.JobManager.dispatch(JobManager.java:1152)
at oracle.reports.server.ConnectionImpl.runJob(ConnectionImpl.java:1285)
at oracle.reports.server._ConnectionImplBase._invoke(_ConnectionImplBase.java:401)
at com.sun.corba.se.internal.corba.ServerDelegate.dispatch(ServerDelegate.java:355)
at com.sun.corba.se.internal.iiop.ORB.process(ORB.java:280)
at com.sun.corba.se.internal.iiop.RequestProcessor.process(RequestProcessor.java:81)
at com.sun.corba.se.internal.orbutil.ThreadPool$PooledThread.run(ThreadPool.java:106)
): Internal error org.omg.CORBA.COMM_FAILURE: vmcid: SUN minor code: 201 completed: No
[2009/7/15 2:23:32:854] State 56004 (EngineInfo:setState): Engine rwEng-0 state is: Shutdown
[2009/7/15 2:23:32:854] Info 56047 (EngineManager:remove): Reports Server shut down engine rwEng-0
[2009/7/15 2:23:32:854] State 56016 (JobManager:updateJobStatus): Job 868 status is: Terminated with error:
REP-56048: Engine rwEng-0 crashed, job Id: 868
[2009/7/15 2:23:32:854] Debug 50103 (JobObject:writeLongUTF): Number of Break Index values=0
[2009/7/15 2:23:32:854] Debug 50103 (JobObject:writeLongUTF): String= Engine rwEng-0 crashed, job Id: 868 strlen=35 utflen=35
[2009/7/15 2:23:32:854] Debug 50103 (JobManager:notifyWaitingJobs): Master job 868 notify its duplicated jobs.
[2009/7/15 2:23:32:854] Debug 50103 (JobManager:updateJobStatus): Finished updating job: 868

 


rwEng-x.trc

Trace information when it works
~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
[2009/7/15 2:23:22:76] Info 50132 (EngineImpl:setCommandLine): Get command line: authid=oracle jobname="<Report Name>" blankpages="no" copies="1" server="<Reports Server Name>" expiredays=0 report="<Report Name>" userid=<user/*****@database> mode=bitmap destination=<Distribution XML file>.xml distribute=yes
[2009/7/15 2:23:22:77] Debug 50103 (EngineImpl:setCommandLine): Quit
[2009/7/15 2:23:22:78] Debug 50103 (EngineImpl:run): Running Jobid=867
[2009/7/15 2:23:22:421] (C Engine) System parameter BACKGROUND is obsolete.
[2009/7/15 2:23:22:423] (rwfdt:rwfdtprint) Distributing the report
[2009/7/15 2:23:22:424] (rwfdt:rwfdtpredo) running
[2009/7/15 2:23:22:425] (rwfdt:rwfdtpredo) Parsing destination file <Distribution XML file>.xml
[2009/7/15 2:23:22:883] (C Engine) Creating XML parser object...
[2009/7/15 2:23:22:887] (C Engine) XML Parser Created!
[2009/7/15 2:23:22:889] (C Engine) Parsing report definition from:
[2009/7/15 2:23:22:890] (C Engine) /tmp/fd_err000210859.xml
[2009/7/15 2:23:22:926] (C Engine) Report definition parsed successfully!

[2009/7/15 2:23:22:930] (rwfdt:rwfdtpredo) create VGS state
[2009/7/15 2:23:22:934] (rwfdt:rwfdtpredo) create and initialize backend
[2009/7/15 2:23:22:956] (C Engine)
[2009/7/15 2:23:22:957] Debug 50103 (EngineImpl:getCacheData): Start
[2009/7/15 2:23:22:958] Debug 50103 (EngineImpl:getCacheData): m_jobId = 867
[2009/7/15 2:23:22:958] Debug 50103 (EngineImpl:getCacheData): Quit
[2009/7/15 2:23:22:963] (rwfdt:rwfdtpredo) start formatting pages
[2009/7/15 2:23:22:967] APP 50103 (C Engine): 14:23:22 APP ( Frame
[2009/7/15 2:23:22:968] APP 50103 (C Engine): 14:23:22 APP . ( Repeating Frame RS_FRAME
[2009/7/15 2:23:22:969] APP 50103 (C Engine): 14:23:22 APP .. ( Group G_column_name Local Break: 0 Global Break: 0
.
.
===============
Trace information when it doesn't work
~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
[2009/7/15 2:23:32:779] Info 50132 (EngineImpl:setCommandLine): Get command line: authid=oracle jobname="<Report Name>" blankpages="no" copies="1" server="<Reports Server Name>" expiredays=0 report="<Report Name>" userid=<user/*****@database> mode=bitmap destination=<Distribution XML file>.xml distribute=yes
[2009/7/15 2:23:32:780] Debug 50103 (EngineImpl:setCommandLine): Quit
[2009/7/15 2:23:32:782] Debug 50103 (EngineImpl:run): Running Jobid=868
[2009/7/15 2:23:32:788] DST 50103 (C Engine) System parameter BACKGROUND is obsolete.
[2009/7/15 2:23:32:790] DST 50103 (rwfdt:rwfdtprint) Distributing the report
[2009/7/15 2:23:32:791] DST 50103 (rwfdt:rwfdtpredo) running
[2009/7/15 2:23:32:792] DST 50103 (rwfdt:rwfdtpredo) Parsing destination file <Distribution XML file>.xml
[2009/7/15 2:23:32:800] DST 50103 (C Engine) Creating XML parser object...
[2009/7/15 2:23:32:801] DST 50103 (C Engine) XML Parser Created!
[2009/7/15 2:23:32:802] DST 50103 (C Engine) Parsing report definition from:
[2009/7/15 2:23:32:803] DST 50103 (C Engine) /tmp/fd_err002D10859.xml
[2009/7/15 2:23:32:812] DST 50103 (C Engine) Report definition parsed successfully!

[2009/7/15 2:23:32:814] DST 50103 (rwfdt:rwfdtpredo) create VGS state
[2009/7/15 2:23:32:815] DST 50103 (rwfdt:rwfdtpredo) create and initialize backend
[2009/7/15 2:23:32:828] DST 50103 (C Engine)
[2009/7/15 2:23:32:838] Debug 50103 (EngineImpl:getCacheData): Start
[2009/7/15 2:23:32:838] Debug 50103 (EngineImpl:getCacheData): m_jobId = 868
[2009/7/15 2:23:32:838] Debug 50103 (EngineImpl:getCacheData): Quit

Cause

Sign In with your My Oracle Support account

Don't have a My Oracle Support account? Click to get started

My Oracle Support provides customers with access to over a
Million Knowledge Articles and hundreds of Community platforms