PBCS Fusion Data Integration - "The script has failed to execute"

Robert Angel · Sep 15, 2017 9:50AM

Hi,

I have successfully configured a connection from PBCS to Fusion following the document; -

When I click, Test Connection - I get 'Test Successful'

When I click configure and check the result in Process Details I see a successful message.

When I click 'Initialize' the process takes some time but finally fails.

As looking through the log the essbase connection succeeds and the fusion connection succeeds then my feeling is that I should just log an SR as as this is cloud services the rest is Oracle's problem from there.

Any with experience in this agree or tell me anything I should try before losing the will to live with an Oracle SR?

The main error from the log seems to be =>

2017-09-15 13:38:37,989 INFO [AIF]: Standard Error: java.lang.Exception: Unable to find txt data file for essRequestId: 179735

at com.hyperion.aif.fusion.FusionAdapter.main(FusionAdapter.java:132)

Full log detail =>

2017-09-15 13:38:13,132 INFO [AIF]: FDMEE Process: 1231, Log Level: 5

2017-09-15 13:38:13,132 INFO [AIF]: Location : NA (Partitionkey:null)

2017-09-15 13:38:13,132 INFO [AIF]: Period Name : NA (Period Key:null)

2017-09-15 13:38:13,132 INFO [AIF]: Category Name: NA (Category key:null)

2017-09-15 13:38:13,132 INFO [AIF]: Rule Name : NA (Rule ID:null)

2017-09-15 13:38:14,104 INFO [AIF]: FDM Version: 11.1.2.4.220

2017-09-15 13:38:14,104 INFO [AIF]: Log File Encoding: UTF-8

2017-09-15 13:38:14,675 DEBUG [AIF]: update aif_processes set application_id= \? where process_id =\?

2017-09-15 13:38:14,675 INFO [AIF]: initializeFsGLCloudSource - START

2017-09-15 13:38:14,675 DEBUG [AIF]: Comm.executeFusionService - START

2017-09-15 13:38:14,675 DEBUG [AIF]: Comm.doScriptInit - START

2017-09-15 13:38:14,721 WARN [AIF]: No details found in loads table for Loadid:1231

2017-09-15 13:38:14,721 WARN [AIF]: Query:SELECT AIF_BALANCE_RULES.RULE_ID,

AIF_BALANCE_RULES.RULE_NAME,

AIF_BAL_RULE_LOADS.START_PERIODKEY,

AIF_BAL_RULE_LOADS.END_PERIODKEY,

AIF_BAL_RULE_LOADS.PLAN_TYPE,

AIF_BAL_RULE_LOADS.FILE_NAME_STATIC,

AIF_BAL_RULE_LOADS.FILE_PATH,

AIF_BAL_RULE_LOADS.IMPORT_FROM_SOURCE_FLAG,

AIF_BAL_RULE_LOADS.EXPORT_TO_TARGET_FLAG,

AIF_BAL_RULE_LOADS.EXECUTION_MODE,

AIF_BAL_RULE_LOADS.EXPORT_MODE,

AIF_BAL_RULE_LOADS.CATKEY,

TPOVCATEGORY.CATNAME

FROM AIF_BAL_RULE_LOADS JOIN

AIF_BALANCE_RULES ON

AIF_BAL_RULE_LOADS.RULE_ID = AIF_BALANCE_RULES.RULE_ID

JOIN TPOVCATEGORY ON

TPOVCATEGORY.CATKEY = AIF_BAL_RULE_LOADS.CATKEY

WHERE AIF_BAL_RULE_LOADS.LOADID = ?

2017-09-15 13:38:14,721 WARN [AIF]: Query bind:1231

2017-09-15 13:38:14,722 WARN [AIF]: No details found in partition table for partitionkey:null

2017-09-15 13:38:14,722 WARN [AIF]: Query:SELECT

TPOVPARTITION.PARTNAME,

TPOVPARTITION.PARTIMPGROUP,

TPOVPARTITION.PARTTARGETAPPLICATIONID,

TPOVPARTITION.PARTSOURCESYSTEMID

FROM TPOVPARTITION

WHERE PARTITIONKEY = ?

2017-09-15 13:38:14,722 WARN [AIF]: Query bind:null

2017-09-15 13:38:14,723 WARN [AIF]: No details found in applications table for APPID:null

2017-09-15 13:38:14,723 WARN [AIF]: Query:SELECT AIF_TARGET_APPLICATIONS.APPLICATION_NAME,

AIF_TARGET_APPLICATIONS.ESSBASE_DB_NAME,

AIF_TARGET_APPLICATIONS.TARGET_APPLICATION_TYPE,

AIF_TARGET_APPLICATIONS.APPLICATION_ID,

AIF_TARGET_APPLICATIONS.DATA_LOAD_METHOD

FROM AIF_TARGET_APPLICATIONS WHERE APPLICATION_ID = ?

2017-09-15 13:38:14,723 WARN [AIF]: Query bind:null

2017-09-15 13:38:14,724 WARN [AIF]: No details found in source table for sourceID:null

2017-09-15 13:38:14,724 WARN [AIF]: Query:SELECT AIF_SOURCE_SYSTEMS.SOURCE_SYSTEM_TYPE,

AIF_SOURCE_SYSTEMS.SOURCE_SYSTEM_NAME

FROM AIF_SOURCE_SYSTEMS WHERE

AIF_SOURCE_SYSTEMS.SOURCE_SYSTEM_ID = ?

2017-09-15 13:38:14,724 WARN [AIF]: Query bind:null

2017-09-15 13:38:14,726 WARN [AIF]: No details found in periods table for period Key:null

2017-09-15 13:38:14,726 WARN [AIF]: Query:SELECT TPOVPERIOD.PERIODDESC FROM TPOVPERIOD WHERE PERIODKEY = ?

2017-09-15 13:38:14,726 WARN [AIF]: Query bind:null

2017-09-15 13:38:14,726 DEBUG [AIF]: fdmContext: {TARGETAPPDB=NA, BATCHSCRIPTDIR=/u02/user_projects/instance0/FinancialDataQuality, LOCKEY=null, LOADID=1231, [email protected], RULEID=null, EPMORACLEINSTANCEHOME=/u02/user_projects/instance0, USERLOCALE=en, EPMORACLEHOME=/u02/Oracle/Middleware/EPMSystem11R1}

2017-09-15 13:38:14,730 DEBUG [AIF]: The executeEventScript is set to: NO

2017-09-15 13:38:14,730 DEBUG [AIF]: The AppRootFolder is set to: /u03/inbox

2017-09-15 13:38:14,730 DEBUG [AIF]: The JavaHome is set to: %EPM_ORACLE_HOME%/../jdk160_35

2017-09-15 13:38:14,730 DEBUG [AIF]: The OleDatabaseProvider is set to: ORAOLEDB.ORACLE

2017-09-15 13:38:14,730 DEBUG [AIF]: Comm.doScriptInit - END

2017-09-15 13:38:14,730 DEBUG [AIF]: Comm.executeJythonScript - START

2017-09-15 13:38:14,731 INFO [AIF]: Executing the following script: /u02/Oracle/Middleware/EPMSystem11R1/products/FinancialDataQuality/bin/FusionCloud/FusionCloudAdapter.py

2017-09-15 13:38:14,740 INFO [AIF]: FusionCloudAdapter.extractCubeInformation - START

2017-09-15 13:38:14,740 INFO [AIF]: FusionCloudAdapter.fusionCloudHelper - START

2017-09-15 13:38:14,740 DEBUG [AIF]: /u02/Oracle/Middleware/EPMSystem11R1/products/FinancialDataQuality/bin/FusionCloud/FusionCloudAdapter.sh "/u02/Oracle/Middleware/EPMSystem11R1" "/u02/user_projects/instance0" "extractCubeInformation" "1231"

2017-09-15 13:38:37,989 INFO [AIF]: Standard Output: INFO: Starting script FusionCloudAdapter ...

PBCS Production Deployment setup proxy

Starting FusionAdapter main program...

prod mode: extractCubeInformation, pid: 1231

Initializing FusionAdapter in prod mode...

fusionIntegrationType: V2

fusionGlWebServiceWsdl: https://xxxxxxx.em2.oraclecloud.com/publicFinancialCommonErpIntegration/ErpIntegrationService?WSDL

fusionGlWebServiceUser: [email protected]

batchTimeoutInMinutes: 30

fusionProductType: null

FusionAdapter initialized fine.

fusionAdapter.processRuleType: null

Submitted request to extract cube information, Ess request: 179735

Current polled request status is: WAIT

Current polled request status is: SUCCEEDED

Ess request has completed fine.

Document Name: 179735.zip

Skipping file: /tmp/179735.log

downloadedDataFileFullName: null

The FusionAdapter main program has completed.

INFO: The script FusionCloudAdapter has failed as reported above.

2017-09-15 13:38:37,989 INFO [AIF]: Standard Error: java.lang.Exception: Unable to find txt data file for essRequestId: 179735

at com.hyperion.aif.fusion.FusionAdapter.main(FusionAdapter.java:132)

2017-09-15 13:38:37,989 ERROR [AIF]: The script has failed to execute:

2017-09-15 13:38:37,990 FATAL [AIF]: Error in Comm.executeJythonScript

Traceback (most recent call last):

File "<string>", line 557, in executeJythonScript

File "/u02/Oracle/Middleware/EPMSystem11R1/products/FinancialDataQuality/bin/FusionCloud/FusionCloudAdapter.py", line 146, in <module>

fusionCloudAdapter.extractCubeInformation()

File "/u02/Oracle/Middleware/EPMSystem11R1/products/FinancialDataQuality/bin/FusionCloud/FusionCloudAdapter.py", line 16, in extractCubeInformation

self.fusionCloudHelper("extractCubeInformation")

File "/u02/Oracle/Middleware/EPMSystem11R1/products/FinancialDataQuality/bin/FusionCloud/FusionCloudAdapter.py", line 120, in fusionCloudHelper

raise RuntimeError

RuntimeError

2017-09-15 13:38:37,991 ERROR [AIF]: Error in initializeFsGLCloudSource.

2017-09-15 13:38:37,991 FATAL [AIF]: Error in initializeFsGLCloudSource

Traceback (most recent call last):

File "<string>", line 451, in initializeFsGLCloudSource

File "<string>", line 475, in executeFusionService

File "<string>", line 557, in executeJythonScript

File "/u02/Oracle/Middleware/EPMSystem11R1/products/FinancialDataQuality/bin/FusionCloud/FusionCloudAdapter.py", line 146, in <module>

fusionCloudAdapter.extractCubeInformation()

File "/u02/Oracle/Middleware/EPMSystem11R1/products/FinancialDataQuality/bin/FusionCloud/FusionCloudAdapter.py", line 16, in extractCubeInformation

self.fusionCloudHelper("extractCubeInformation")

File "/u02/Oracle/Middleware/EPMSystem11R1/products/FinancialDataQuality/bin/FusionCloud/FusionCloudAdapter.py", line 120, in fusionCloudHelper

raise RuntimeError

RuntimeError

2017-09-15 13:38:38,184 INFO [AIF]: FDMEE Process End, Process ID: 1231

JohnGoodwin · Sep 15, 2017 10:11AM

It looks like on the fusion side a text file was not created from the executed task to extract cube definitions.

In your log there is

downloadedDataFileFullName: null

when it should have been

downloadedDataFileFullName: /tmp/179735.txt

If you log into fusion and go to scheduled processes, you should be able to search for process id 179735 and look at the log to see if there is additional information.

Cheers

John

JohnGoodwin · Sep 15, 2017 10:11AM

It looks like on the fusion side a text file was not created from the executed task to extract cube definitions.

In your log there is

downloadedDataFileFullName: null

when it should have been

downloadedDataFileFullName: /tmp/179735.txt

If you log into fusion and go to scheduled processes, you should be able to search for process id 179735 and look at the log to see if there is additional information.

Cheers

John

Robert Angel · Sep 15, 2017 10:13AM

Many thanks John.

Is there some additional config required in fusion then, that the document does not detail?

Robert Angel · Sep 15, 2017 10:43AM

Hi John,

when I get the log in fusion it says successful but I see no output.....

Colour me confused!!

execute:Start( RequestExecutionContext requestExecutionContext = 179738, false, null, 57F524D271F4332BE0539677660A4653, 12, 1

Request for ExtractCubeInformation running with request ID: 179738

getOutputFile:Start

getOutputFile: Attempting to get LogFile from ProcessHelper

getOutputFile:End

-------------------------------------------------------

getOlapserver() :: <Essbase Server Host:port> = bi.oracleoutsourcing.com:10215

ExtractCubeInformation:Info::Started of Extracting GL Cube Information at ::::: 1505485474017

creating am with: Name: oracle.apps.financials.generalLedger.ledgers.essbase.publicModel.applicationModule.LedgerEssbaseAM Config: LedgerEssbaseAMLocal

Created Application module instance

ExtractCubeInformation:Info::End of Extracting GL Cube Information at ::::: 1505485474033

ExtractCubeInformation:Info::Started Extracting XCC Cube Information ::::: 1505485474033

creating am with: Name: oracle.apps.financials.generalLedger.ledgers.essbase.publicModel.applicationModule.LedgerEssbaseAM Config: LedgerEssbaseAMLocal

Created Application module instance

ExtractCubeInformation:Info::End of Extracting XCC Cube Information ::::: 1505485474065

Request for ExtractCubeInformation completed with request ID: 179738

JohnGoodwin · Sep 15, 2017 12:20PM

Strange, there should be a text file attachment produced, for example

Robert Angel · Sep 18, 2017 11:24AM

One last question - this functionality does work pre-patch for version 12 - yes?

You should have a new status for PBCS - life saver....

Many, many thanks,

Robert.

JohnGoodwin · Sep 18, 2017 11:32AM

The last time I ran an integration with fusion was with release 11

Forum Stats

Quick Links

Categories

PBCS Fusion Data Integration - "The script has failed to execute"

Best Answer

Answers

On-Premise Applications

On-Premise Infrastructure

Industry Applications

Leadership Forums

On-Premise Applications

On-Premise Infrastructure

Industry Applications

Leadership Forums

Forum Stats

Categories

PBCS Fusion Data Integration - "The script has failed to execute"

Best Answer

Answers