Failed ATL: #__AW_Repository_Version '14.2.3.0000';

[former_member215452]

Hi Robert,

We got similar issue below are the logs found. Can you please suggest us.

[C] 2017-10-23 14:59:30.757 [TaskUtility][main] Initializing environment from EnterpriseNode 789. [I] 2017-10-23 14:59:30.758 [TaskUtility][main] Information Steward version 14.2.9.1613. [I] 2017-10-23 14:59:30.768 [TaskUtility][main] Starting utility com.sap.icc.task.TaskUtility. (COR-10013) [I] 2017-10-23 14:59:30.768 [TaskUtility][main] Process ID = 308104 on machine MN600D0C001.chsinc.ds. [C] 2017-10-23 14:59:30.784 [TaskUtility][main] Property MaxConnections value is 10. (COR-10002) [C] 2017-10-23 14:59:30.784 [TaskUtility][main] Property ConnectionTimeout value is 3600 seconds. (COR-10002) [C] 2017-10-23 14:59:30.785 [TaskUtility][main] Property PoolTime value is 300 seconds. (COR-10002) [C] 2017-10-23 14:59:30.823 [TaskUtility][main] Loading database driver com.microsoft.sqlserver.jdbc.SQLServerDriver for database engine MS SQL Server 2012. [I] 2017-10-23 14:59:31.646 [TaskUtility][main] Connected to the Information Steward repository at jdbc:sqlserver://mn600d0c001:1433;databaseName=HD8_IS_REPO;integratedSecurity=true. (COR-10017) [C] 2017-10-23 14:59:31.782 [TaskUtility][main] Argument -configurationObject = 40428 (COR-10206) [C] 2017-10-23 14:59:31.783 [TaskUtility][main] Argument -force = false (COR-10206) [C] 2017-10-23 14:59:31.783 [TaskUtility][main] Argument -priority = 5 (COR-10206) [C] 2017-10-23 14:59:31.783 [TaskUtility][main] Argument -returnScore = false (COR-10206) [C] 2017-10-23 14:59:31.783 [TaskUtility][main] Argument -singleProcess = null (COR-10206) [C] 2017-10-23 14:59:31.783 [TaskUtility][main] Argument -workload = 0 (COR-10206) [C] 2017-10-23 14:59:31.783 [TaskUtility][main] Argument DSEngine.timeout = 10 (COR-10206) [C] 2017-10-23 14:59:31.783 [TaskUtility][main] Argument DataInsight.useABAP = true (COR-10206) [C] 2017-10-23 14:59:31.783 [TaskUtility][main] Argument Metapedia.exportAssociatedObjects = true (COR-10206) [C] 2017-10-23 14:59:31.783 [TaskUtility][main] Argument batch = false (COR-10206) [C] 2017-10-23 14:59:31.784 [TaskUtility][main] Argument batchRetryCount = 4,320 (COR-10206) [C] 2017-10-23 14:59:31.784 [TaskUtility][main] Argument batchRetryInterval = 10,000 (COR-10206) [C] 2017-10-23 14:59:31.784 [TaskUtility][main] Argument boeAuthentication = secEnterprise (COR-10206) [C] 2017-10-23 14:59:31.784 [TaskUtility][main] Argument boeInfoObjectId = 40428 (COR-10206) [C] 2017-10-23 14:59:31.784 [TaskUtility][main] Argument boeServer = MN600D0C001.chsinc.ds (COR-10206) [C] 2017-10-23 14:59:31.784 [TaskUtility][main] Argument boeUser = Administrator (COR-10206) [C] 2017-10-23 14:59:31.784 [TaskUtility][main] Argument className = com.sap.icc.task.TaskUtility (COR-10206) [C] 2017-10-23 14:59:31.784 [TaskUtility][main] Argument deadlockRetryCount = 10 (COR-10206) [C] 2017-10-23 14:59:31.784 [TaskUtility][main] Argument enableNewDBOptimization = true (COR-10206) [C] 2017-10-23 14:59:31.785 [TaskUtility][main] Argument maxConnections = 10 (COR-10206) [C] 2017-10-23 14:59:31.785 [TaskUtility][main] Argument repoConnectionTimeout = 3,600 (COR-10206) [C] 2017-10-23 14:59:31.785 [TaskUtility][main] Argument repoOverridePermitted = false (COR-10206) [C] 2017-10-23 14:59:31.785 [TaskUtility][main] Argument sqlTopLimit = 100,000 (COR-10206) [C] 2017-10-23 14:59:31.785 [TaskUtility][main] Argument task.cleanFD = false (COR-10206) [C] 2017-10-23 14:59:31.785 [TaskUtility][main] Argument task.deleteFD = false (COR-10206) [I] 2017-10-23 14:59:32.345 [TaskUtility][main] Started execution on 2017-10-23 14:59:30. (COR-10014) [C] 2017-10-23 14:59:32.443 [TaskUtility][main] License validation done successfully. [I] 2017-10-23 14:59:32.444 [TaskUtility][main] Utility processing starts. [I] 2017-10-23 14:59:32.456 [TaskUtility][main] Started Task process Business Partner Address Scorecard [task ID=2]. (TSK-10025) [I] 2017-10-23 14:59:32.474 [TaskUtility][main] Initializing Subtask queue with information from Task. (TSK-10040) [I] 2017-10-23 14:59:33.017 [TaskUtility][pool-2-thread-1] Remote - Standard Object Type Document [E] 2017-10-23 14:59:46.676 [TaskUtility][pool-2-thread-1] Failed ATL: #__AW_Repository_Version '14.2.3.0000'; #__AW_Product_Version '14.2.3.0000'; #__AW_ATL_Locale 'eng_us.utf-8'; CREATE __BODI_SUBVARSTORE SET ( "SV_Config_Default" = 'Configuration1' , "SV_Config_Values" = '<?xml version="1.0" encoding="UTF-8"?> <SVConfigurations> <SVConfiguration name="Configuration1"> <SubVar name="InformationStewardDSTaskUniqueID"></SubVar><SubVar name="ISRunID"></SubVar><SubVar name="sv_task_subrun"></SubVar><SubVar name="ISDeleteFD"></SubVar> </SVConfiguration> </SVConfigurations>' ); CREATE __BODI_SUBVARSTORE SET ( "SV_Config_Default" = 'Configuration1' , "SV_Config_Values" = '<?xml version="1.0" encoding="UTF-8"?> <SVConfigurations> <SVConfiguration name="Configuration1"> <SubVar name="InformationStewardDSTaskUniqueID"></SubVar><SubVar name="ISRunID"></SubVar><SubVar name="sv_task_subrun"></SubVar><SubVar name="ISDeleteFD"></SubVar> </SVConfiguration> </SVConfigurations>' ); [W] 2017-10-23 14:59:46.679 [TaskUtility][pool-2-thread-1] Import of ATL failed :: Error com.sap.icc.dataservices.api.DSAPIException: Data Services execution failed for [-f"C:\Users\ISDSAD~1\AppData\Local\Temp\is_22169104321136157603705839326523710039.atl"]. Error : (14.2) 10-23-17 14:59:46 (E) (306128:308916) REP-100106: Initialization Cannot run version <14.2.9.1644> of the product against the earlier version <14.2.7.0000> of the repository. Upgrade the repository to version <V14.2.9.0000> or later. (COR-10690) [E] 2017-10-23 14:59:46.879 [TaskUtility][pool-2-thread-1] Failed ATL: #__AW_Repository_Version '14.2.3.0000'; #__AW_Product_Version '14.2.3.0000'; #__AW_ATL_Locale 'eng_us.utf-8'; CREATE __BODI_SUBVARSTORE SET ( "SV_Config_Default" = 'Configuration1' , "SV_Config_Values" = '<?xml version="1.0" encoding="UTF-8"?> <SVConfigurations> <SVConfiguration name="Configuration1"> <SubVar name="InformationStewardDSTaskUniqueID"></SubVar><SubVar name="ISRunID"></SubVar><SubVar name="sv_task_subrun"></SubVar><SubVar name="ISDeleteFD"></SubVar> </SVConfiguration> </SVConfigurations>' ); CREATE __BODI_SUBVARSTORE SET ( "SV_Config_Default" = 'Configuration1' , "SV_Config_Values" = '<?xml version="1.0" encoding="UTF-8"?> <SVConfigurations> <SVConfiguration name="Configuration1"> <SubVar name="InformationStewardDSTaskUniqueID"></SubVar><SubVar name="ISRunID"></SubVar><SubVar name="sv_task_subrun"></SubVar><SubVar name="ISDeleteFD"></SubVar> </SVConfiguration> </SVConfigurations>' ); [E] 2017-10-23 14:59:46.883 [TaskUtility][pool-5-thread-1] Import of ATL failed :: Error com.sap.icc.dataservices.api.DSAPIException: Data Services execution failed for [-f"C:\Users\ISDSAD~1\AppData\Local\Temp\is_22169104321136157624678824261556864725.atl"]. Error : (14.2) 10-23-17 14:59:46 (E) (294816:306184) REP-100106: Initialization Cannot run version <14.2.9.1644> of the product against the earlier version <14.2.7.0000> of the repository. Upgrade the repository to version <V14.2.9.0000> or later. (COR-10690) [E] 2017-10-23 14:59:46.886 [TaskUtility][pool-5-thread-1] com.sap.icc.dataservices.api.DSAPIException: Import of ATL failed :: Error com.sap.icc.dataservices.api.DSAPIException: Data Services execution failed for [-f"C:\Users\ISDSAD~1\AppData\Local\Temp\is_22169104321136157624678824261556864725.atl"]. Error : (14.2) 10-23-17 14:59:46 (E) (294816:306184) REP-100106: Initialization Cannot run version <14.2.9.1644> of the product against the earlier version <14.2.7.0000> of the repository. Upgrade the repository to version <V14.2.9.0000> or later. (COR-10690) at com.sap.icc.dataservices.api.ISDSEngine.runImportObject(ISDSEngine.java:359) at com.sap.icc.dataservices.api.ISDSEngine.processCaches(ISDSEngine.java:276) at com.sap.icc.dataservices.api.ISDSEngine.flush(ISDSEngine.java:124) at com.sap.icc.dataservices.api.ExecuteTask.importTask(ExecuteTask.java:107) at com.sap.icc.task.DataServiceEngineFacade$1.call(DataServiceEngineFacade.java:70) at com.sap.icc.task.DataServiceEngineFacade$1.call(DataServiceEngineFacade.java:68) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:812) Caused by: com.sap.icc.dataservices.api.DSAPIException: Data Services execution failed for [-f"C:\Users\ISDSAD~1\AppData\Local\Temp\is_22169104321136157624678824261556864725.atl"]. Error : (14.2) 10-23-17 14:59:46 (E) (294816:306184) REP-100106: Initialization Cannot run version <14.2.9.1644> of the product against the earlier version <14.2.7.0000> of the repository. Upgrade the repository to version <V14.2.9.0000> or later. (COR-10690) at com.sap.icc.dataservices.api.RunEngineCmd.runEngine(RunEngineCmd.java:149) at com.sap.icc.dataservices.api.RunEngineCmd.runEngineCmd(RunEngineCmd.java:66) at com.sap.icc.dataservices.api.ISDSEngine.runImportObject(ISDSEngine.java:333) ... 9 more [I] 2017-10-23 14:59:46.887 [TaskUtility][pool-5-thread-1] Stopping subtask process [task id=2, subtask id=581]. (TSK-10085) [E] 2017-10-23 14:59:46.905 [TaskUtility][pool-5-thread-1] The subtask process [subtask ID=0] has been partially completed in {1} ms. (TSK-10094) [I] 2017-10-23 14:59:47.039 [TaskUtility][pool-5-thread-1] Processed [1] of [1] sub tasks. (TSK-10047) [I] 2017-10-23 14:59:47.041 [TaskUtility][main] Summary of task [Business Partner Address Scorecard] with [1] subtask(s) executed. (TSK-10005) [I] 2017-10-23 14:59:47.042 [TaskUtility][main] [1] Subtask(s) executed with errors. (TSK-10007) [I] 2017-10-23 14:59:47.058 [TaskUtility][main] [0] Rules executed successfully. (TSK-10009) [I] 2017-10-23 14:59:47.117 [TaskUtility][main] Subtask [run id=1] Table dbo.vw_DM_BusinessPartner_GeneralData_Address_Scorecard: Internal error: Import of ATL failed :: Error com.sap.icc.dataservices.api.DSAPIException: Data Services execution failed for [-f"C:\Users\ISDSAD~1\AppData\Local\Temp\is_22169104321136157624678824261556864725.atl"]. Error : (14.2) 10-23-17 14:59:46 (E) (294816:306184) REP-100106: Initialization Cannot run version <14.2.9.1644> of the product against the earlier version <14.2.7.0000> of the repository. Upgrade the repository to version <V14.2.9.0000> or later. (COR-10690). Check with your system administrator if error occurs again. (TSK-10072) (TSK-10008) [I] 2017-10-23 14:59:47.186 [TaskUtility][main] Generating rule task email notification. (TSK-10014) [E] 2017-10-23 14:59:47.211 [TaskUtility][main] Error when creating alert message for Rule : select tab.display_name, sts.score, sts.ctable_id, sts.version, sts.total_rows, sts.pass_rows, sts.error_rows, sts.prior_score, sts.threshold from dbo.MMD_Ep_Ctable_Map tab, dbo.MMD_Ep_Rule_Statistic sts, dbo.MMD_Task_Queue tsk where tsk.job_id = ? and tsk.status_code = ? and sts.ctable_id = tsk.table_id and sts.statistic_category = ? and sts.threshold = ? and sts.version = (select max(version) from dbo.MMD_Ep_Proj_Ver_Map map where map.project_id = tsk.project_id and map.ctable_id = tsk.table_id and map.is_valid = ? and map.task_type = ?) and tab.ctable_id = tsk.ctable_id (TSK-10087) [I] 2017-10-23 14:59:47.404 [TaskUtility][main] Task process finished with errors. Status is set to failed. (TSK-10027) [I] 2017-10-23 14:59:47.405 [TaskUtility][main] Utility processing ended. [E] 2017-10-23 14:59:47.421 [TaskUtility][main] Utility failed with code 4. (COR-10020) [I] 2017-10-23 14:59:47.422 [TaskUtility][main] Completed execution on 2017-10-23 14:59:47. (COR-10015) [I] 2017-10-23 14:59:47.423 [TaskUtility][main] Elapsed time is 0:16.691 [min:sec.millisec]. (COR-10084) [C] 2017-10-23 14:59:47.435 [TaskUtility][main] SQL SELECT statements: count=1, rows=1, bytes=138, msec=33 for table Actor. (COR-10849) [C] 2017-10-23 14:59:47.436 [TaskUtility][main] SQL DELETE statements: count=1, rows=0, msec=10 for table Checkpoint. (COR-10852) [C] 2017-10-23 14:59:47.436 [TaskUtility][main] SQL DELETE statements: count=1, rows=0, msec=1 for table Collection_Object_Association. (COR-10852) [C] 2017-10-23 14:59:47.436 [TaskUtility][main] SQL SELECT statements: count=2, rows=2, bytes=26, msec=35 for table Connection. (COR-10849) [C] 2017-10-23 14:59:47.437 [TaskUtility][main] SQL UPDATE statements: count=1, rows=0, bytes=0, msec=17 for table Custom_Field_Value. (COR-10851) [C] 2017-10-23 14:59:47.437 [TaskUtility][main] SQL SELECT statements: count=5, rows=88, bytes=8,896, msec=25 for table Data_Element. (COR-10849) [C] 2017-10-23 14:59:47.437 [TaskUtility][main] SQL SELECT statements: count=5, rows=5, bytes=740, msec=73 for table Data_Group. (COR-10849) [C] 2017-10-23 14:59:47.437 [TaskUtility][main] SQL SELECT statements: count=3, rows=3, bytes=670, msec=5 for table Database_Connection. (COR-10849) [C] 2017-10-23 14:59:47.438 [TaskUtility][main] SQL SELECT statements: count=1, rows=1, bytes=55, msec=4 for table Deployed_Software_Module. (COR-10849) [C] 2017-10-23 14:59:47.438 [TaskUtility][main] SQL UPDATE statements: count=1, rows=0, bytes=0, msec=3 for table Dictionary. (COR-10851) [C] 2017-10-23 14:59:47.438 [TaskUtility][main] SQL SELECT statements: count=2, rows=158, bytes=3,496, msec=20 for table Ep_Col_Map. (COR-10849) [C] 2017-10-23 14:59:47.438 [TaskUtility][main] SQL SELECT statements: count=2, rows=2, bytes=268, msec=11 for table Ep_Ctable_Map. (COR-10849) [C] 2017-10-23 14:59:47.439 [TaskUtility][main] SQL SELECT statements: count=10, rows=10, bytes=466, msec=18 for table Ep_Ds_Obj. (COR-10849) [C] 2017-10-23 14:59:47.439 [TaskUtility][main] SQL SELECT statements: count=1, rows=1, bytes=8, msec=1 for table Ep_Max_Val. (COR-10849) [C] 2017-10-23 14:59:47.439 [TaskUtility][main] SQL UPDATE statements: count=2, rows=1, bytes=40, msec=2 for table Ep_Max_Val. (COR-10851) [C] 2017-10-23 14:59:47.439 [TaskUtility][main] SQL SELECT statements: count=1, rows=0, bytes=0, msec=28 for table Ep_Rule_Statistic. (COR-10849) [C] 2017-10-23 14:59:47.440 [TaskUtility][main] SQL SELECT statements: count=1, rows=1, bytes=31, msec=1 for table Ep_Run. (COR-10849) [C] 2017-10-23 14:59:47.440 [TaskUtility][main] SQL INSERT statements: count=1, rows=1, bytes=35, msec=1 for table Ep_Run. (COR-10850) [C] 2017-10-23 14:59:47.440 [TaskUtility][main] SQL UPDATE statements: count=1, rows=1, bytes=35, msec=1 for table Ep_Run. (COR-10851) [C] 2017-10-23 14:59:47.440 [TaskUtility][main] SQL DELETE statements: count=1, rows=1, msec=4 for table Ep_Run. (COR-10852) [C] 2017-10-23 14:59:47.440 [TaskUtility][main] SQL INSERT statements: count=1, rows=1, bytes=63, msec=2 for table Ep_Subrun. (COR-10850) [C] 2017-10-23 14:59:47.441 [TaskUtility][main] SQL DELETE statements: count=1, rows=1, msec=3 for table Ep_Subrun. (COR-10852) [C] 2017-10-23 14:59:47.441 [TaskUtility][main] SQL SELECT statements: count=6, rows=10, bytes=15,095, msec=30 for table Extended_Text. (COR-10849) [C] 2017-10-23 14:59:47.441 [TaskUtility][main] SQL INSERT statements: count=4, rows=4, bytes=7,370, msec=65 for table Extended_Text. (COR-10850) [C] 2017-10-23 14:59:47.441 [TaskUtility][main] SQL UPDATE statements: count=1, rows=4, bytes=0, msec=5 for table Extended_Text. (COR-10851) [C] 2017-10-23 14:59:47.442 [TaskUtility][main] SQL SELECT statements: count=2, rows=2, bytes=122, msec=10 for table Lock. (COR-10849) [C] 2017-10-23 14:59:47.442 [TaskUtility][main] SQL UPDATE statements: count=5, rows=5, bytes=135, msec=7 for table Lock. (COR-10851) [C] 2017-10-23 14:59:47.442 [TaskUtility][main] SQL SELECT statements: count=1, rows=1, bytes=23, msec=3 for table Parameter. (COR-10849) [C] 2017-10-23 14:59:47.444 [TaskUtility][main] SQL SELECT statements: count=2, rows=2, bytes=402, msec=30 for table Profile_Project. (COR-10849) [C] 2017-10-23 14:59:47.445 [TaskUtility][main] SQL SELECT statements: count=2, rows=79, bytes=7,999, msec=154 for table Relational_Column. (COR-10849) [C] 2017-10-23 14:59:47.445 [TaskUtility][main] SQL SELECT statements: count=1, rows=0, bytes=0, msec=84 for table Relational_Index. (COR-10849) [C] 2017-10-23 14:59:47.445 [TaskUtility][main] SQL SELECT statements: count=1, rows=1, bytes=86, msec=14 for table Relational_Schema. (COR-10849) [C] 2017-10-23 14:59:47.445 [TaskUtility][main] SQL SELECT statements: count=1, rows=1, bytes=221, msec=7 for table Relational_Table. (COR-10849) [C] 2017-10-23 14:59:47.446 [TaskUtility][main] SQL SELECT statements: count=9, rows=22, bytes=1,897, msec=9,740 for table Relationship. (COR-10849) [C] 2017-10-23 14:59:47.446 [TaskUtility][main] SQL SELECT statements: count=4, rows=4, bytes=6,063, msec=37 for table Rule. (COR-10849) [C] 2017-10-23 14:59:47.446 [TaskUtility][main] SQL SELECT statements: count=1, rows=1, bytes=94, msec=13 for table Server_Component. (COR-10849) [C] 2017-10-23 14:59:47.447 [TaskUtility][main] SQL SELECT statements: count=2, rows=2, bytes=243, msec=12 for table Software_Module. (COR-10849) [C] 2017-10-23 14:59:47.447 [TaskUtility][main] SQL SELECT statements: count=2, rows=2,043, bytes=155,895, msec=36 for table Table. (COR-10849) [C] 2017-10-23 14:59:47.447 [TaskUtility][main] SQL SELECT statements: count=11, rows=6, bytes=8,961, msec=59 for table Task_Queue. (COR-10849) [C] 2017-10-23 14:59:47.447 [TaskUtility][main] SQL INSERT statements: count=1, rows=1, bytes=64, msec=1 for table Task_Queue. (COR-10850) [C] 2017-10-23 14:59:47.448 [TaskUtility][main] SQL UPDATE statements: count=2, rows=2, bytes=99, msec=5 for table Task_Queue. (COR-10851) [C] 2017-10-23 14:59:47.448 [TaskUtility][main] SQL SELECT statements: count=4, rows=4, bytes=308, msec=15 for table Threshold. (COR-10849) [C] 2017-10-23 14:59:47.448 [TaskUtility][main] SQL SELECT statements: count=6, rows=263, bytes=17,537, msec=67 for table Type_Code. (COR-10849) [C] 2017-10-23 14:59:47.448 [TaskUtility][main] SQL SELECT statements: count=1, rows=1, bytes=118, msec=27 for table Utility. (COR-10849) [C] 2017-10-23 14:59:47.449 [TaskUtility][main] SQL SELECT statements: count=1, rows=1, bytes=107, msec=2 for table Utility_Audit. (COR-10849) [C] 2017-10-23 14:59:47.449 [TaskUtility][main] SQL INSERT statements: count=1, rows=1, bytes=48, msec=2 for table Utility_Audit. (COR-10850) [C] 2017-10-23 14:59:47.449 [TaskUtility][main] SQL UPDATE statements: count=1, rows=1, bytes=75, msec=2 for table Utility_Audit. (COR-10851) [C] 2017-10-23 14:59:47.449 [TaskUtility][main] SQL INSERT statements: count=28, rows=28, bytes=1,092, msec=93 for table Utility_Log. (COR-10850) [C] 2017-10-23 14:59:47.549 [TaskUtility][main] SQL INSERT statements: count=3, rows=38, bytes=3,370, msec=94 for table Repository_Statistic. (COR-10850) [C] 2017-10-23 14:59:47.549 [TaskUtility][main] Information Steward repository connections are shutting down. (COR-10367)

[former_member213714]

Hi,

Did this start happening after an upgrade? Log indicates you are on Information Steward 14.2.9.1644, but later in the log you see the following error:

REP-100106: Initialization Cannot run version <14.2.9.1644> of the product against the earlier version <14.2.7.0000> of the repository. Upgrade the repository to version <V14.2.9.0000> or later.

It would seem the repo did not get upgraded properly as it still shows as being 14.2.7.0000. Check the repo version in the AL_VERSION table of the repository database. If it is still 14.2.7.0000 as the error suggests, you will need to manually upgrade the repository. See the IS admin guide for information on the ISRepositoryUtility which can be used to upgrade the repo.

Regards,

Doug