1 Reply Latest reply on Feb 2, 2016 2:48 AM by Narsi M-Oracle

    EBS R12.2 -> FAILED: adop phase=prepare

    wikki26

      Hi,

       

      I have apply a patch, i am using EBS R12.2.0 with DB 11.2.0.3.0

       

      I have give command from run environment (fs1) below:

       

      adop phase=prepare

       

      then it is failed after giving some error, error log is given below:

       

       

        [STATEMENT] Log file: D:\ora1\VISAPP\fs_ne/EBSapps/log/adop/5/adop_20160201_235316.log

      Phase: prepare

       

       

      [STATEMENT] [START 2016/02/01 23:53:16] Performing verification of parameters

      [STATEMENT] [END   2016/02/01 23:53:29] Performing verification of parameters

      [STATEMENT] Sourcing the Run Edition environment: D:\ora1\VISAPP\fs1\EBSapps\appl\APPSVIS_srcebs.cmd

      [STATEMENT] [START 2016/02/01 23:53:30] Checking for the required ENV setup

      [STATEMENT] [END   2016/02/01 23:53:30] Checking for the required ENV setup

      [STATEMENT] [START 2016/02/01 23:53:30] Determining admin node

      [STATEMENT] [END   2016/02/01 23:53:33] Determining admin node

      [STATEMENT] [START 2016/02/01 23:53:37] Acquiring lock on sessions table

      [STATEMENT] SQL stmt: <       begin

                AD_ZD_ADOP.LOCK_SESSIONS_TABLE('srcebs',60,2);

             end;

      >

       

       

      [STATEMENT] [END   2016/02/01 23:53:40] Acquiring lock on sessions table

      [STATEMENT] [START 2016/02/01 23:53:40] Checking for any pending sessions

      [STATEMENT] inside checkAciveHotPatchSession

      [STATEMENT] Statement: <    select count(1) from ad_adop_sessions

           where status <> 'C' and prepare_status='X' and cutover_status='X' and

           abort_status='X' and apply_status in ('N','P') and cleanup_status='N'

           and appltop_id=2045 and node_name='srcebs'

      >.

      [STATEMENT] value of incomplete_session: <0>

      [STATEMENT] leaving checkAciveHotPatchSession

      [STATEMENT] Master Session Id: <>

      [STATEMENT] [START 2016/02/01 23:53:53] Staging the current session

        [STATEMENT] statement: <select ad_adop_session_id_seq.nextval from dual>

        [STATEMENT] [START 2016/02/01 23:54:07] Checking if node "srcebs" is abandoned

          [STATEMENT] statement: <         select abandon_flag from ad_adop_sessions

               where node_name='srcebs' and abandon_flag is not null and

               adop_session_id = (select max(adop_session_id) from ad_adop_sessions where

                                  ((prepare_status='Y' and apply_status='Y' and cutover_status='Y') or (abort_status='Y'))

                                  and node_type='master'

                                 )

      >

        [STATEMENT] [END   2016/02/01 23:54:14] Checking if node "srcebs" is abandoned

        [STATEMENT] SQL stmt: <        insert into ad_adop_sessions

                (adop_session_id, prepare_status, apply_status, finalize_status, cutover_status,

                 cleanup_status,abort_status,session_input_data,status,appltop_id,node_name,node_type,abandon_flag)

              values (5,'N','N','N','N','N','N','','N','2045','srcebs','master', 5)

      >

       

       

      [STATEMENT] [END   2016/02/01 23:54:21] Staging the current session

      [STATEMENT] input_string: admin_node#srcebs;abortmode#0;LOG_LEVEL_VALUES#HASH(0x36d52f0);num_tries#2;workers#2;max_workers#5;logidentmode#SUBROUTINE;LOG_LEVEL#HASH(0x36d5188);hotpatch#0;logfile#adop_20160201_235316.log;shared_fs#0;patchcontextfile#D:\ora1\VISAPP\fs2\inst\apps\VIS_srcebs\appl\admin\VIS_srcebs.xml;recom_workers#2;session_id#5;lock_wait_interval#60;loglevel#statement;patchtop#D:\ora1\VISAPP\fs_ne\EBSapps\patch;merge#yes;phase#prepare;adpatch_interactive#yes;non_shared_fs_multi_node#0;multi_node#0;mtrestart#1;is_master#1;nodbaction#0;autoskip#0;runcontextfile#D:\ora1\VISAPP\fs1\inst\apps\VIS_srcebs\appl\admin\VIS_srcebs.xml;

      [STATEMENT] Session ID: 5

      [STATEMENT] statement: <      update ad_adop_sessions

            set    session_input_data ='admin_node#srcebs;abortmode#0;LOG_LEVEL_VALUES#HASH(0x36d52f0);num_tries#2;workers#2;max_workers#5;logidentmode#SUBROUTINE;LOG_LEVEL#HASH(0x36d5188);hotpatch#0;logfile#adop_20160201_235316.log;shared_fs#0;patchcontextfile#D:\ora1\VISAPP\fs2\inst\apps\VIS_srcebs\appl\admin\VIS_srcebs.xml;recom_workers#2;session_id#5;lock_wait_interval#60;loglevel#statement;patchtop#D:\ora1\VISAPP\fs_ne\EBSapps\patch;merge#yes;phase#prepare;adpatch_interactive#yes;non_shared_fs_multi_node#0;multi_node#0;mtrestart#1;is_master#1;nodbaction#0;autoskip#0;runcontextfile#D:\ora1\VISAPP\fs1\inst\apps\VIS_srcebs\appl\admin\VIS_srcebs.xml;'

            where  adop_session_id =5

              and node_name='srcebs'

      >

      [STATEMENT] [START 2016/02/01 23:54:28] Unlocking sessions table

        [STATEMENT] SQL stmt: <       begin

                AD_ZD_ADOP.UNLOCK_SESSIONS_TABLE('srcebs',60,2);

             end;

      >

       

       

      [STATEMENT] [END   2016/02/01 23:54:31] Unlocking sessions table

      [STATEMENT] [START 2016/02/01 23:54:31] adzdoptl.pl run

        [STATEMENT] Session ID: 5

        [STATEMENT] Phase: prepare

        [STATEMENT] Log file: D:\ora1\VISAPP\fs_ne/EBSapps/log/adop/5/adop_20160201_235316.log

        [STATEMENT] SQL Statement: <    select count(1)

            from  ad_adop_sessions

            where  adop_session_id=5 and (prepare_status in ('N','R'))

      >.

        [STATEMENT] SQL Output: <1>.

        [STATEMENT] SQL Statement:       update ad_adop_sessions

              set status='R'

               where adop_session_id = 5 and appltop_id = 2045 and node_name='srcebs'

       

       

        [STATEMENT] [START 2016/02/01 23:54:55] prepare phase

          [STATEMENT] SQL Statement:         update ad_adop_sessions

              set prepare_start_date=to_date('01-02-2016 23:54:58','DD-MM-YYYY HH24:MI:SS')

              where  adop_session_id=5

       

       

          [STATEMENT] PREPARE Phase START TIME: 01-02-2016 23:54:58

          [STATEMENT] SQL Statement:       update ad_adop_sessions

              set prepare_status='R'

              where adop_session_id=5 and appltop_id=2045  and node_name='srcebs'

       

       

          [STATEMENT] [START 2016/02/01 23:55:10] Validating Configuration

            [STATEMENT] Calling: D:\ora1\VISAPP\fs1\EBSapps\appl\ad\12.0.0/patch/115/bin/txkADOPPreparePhaseSanityCheck.pl

            [STATEMENT] Output directory: D:\ora1\VISAPP\fs_ne/EBSapps/log/adop/5/prepare_20160201_235316/VIS_srcebs

          [STATEMENT] [END   2016/02/01 23:55:21] Validating Configuration

          [STATEMENT] [START 2016/02/01 23:55:24] Detecting config changes in FS

            [STATEMENT] Calling task: <Executing adConfigChangeDetector on srcebs>; script: <D:\ora1\VISAPP\fs1\EBSapps\appl\ad\12.0.0/patch/115/bin/adConfigChangeDetector.pl>; args: < -detectConfigChanges contextfile=D:\ora1\VISAPP\fs1\inst\apps\VIS_srcebs\appl\admin\VIS_srcebs.xml promptmsg=hide log=D:\ora1\VISAPP\fs_ne/EBSapps/log/adop/5/prepare_20160201_235316/VIS_srcebs/adConfigChanges.log hostname=srcebs>

            [STATEMENT] [START 2016/02/01 23:55:28] Executing adConfigChangeDetector on srcebs

              [STATEMENT] Calling: D:\ora1\VISAPP\fs1\EBSapps\appl\ad\12.0.0/patch/115/bin/adConfigChangeDetector.pl

              [STATEMENT] Output directory: D:\ora1\VISAPP\fs_ne/EBSapps/log/adop/5/prepare_20160201_235316/VIS_srcebs/adConfigChanges.log

            [STATEMENT] [END   2016/02/01 23:55:37] Executing adConfigChangeDetector on srcebs

            [STATEMENT] Config changes detected in srcebs

            [STATEMENT] [START 2016/02/01 23:55:40] Insert Config Clone tracking row for NODE: srcebs

            [STATEMENT] [END   2016/02/01 23:55:53] Insert Config Clone tracking row for NODE: srcebs

          [STATEMENT] [END   2016/02/01 23:55:55] Detecting config changes in FS

          [STATEMENT] [START 2016/02/01 23:55:58] Performing check to see if pending cleanup actions exist

            [STATEMENT] Checking if pending cleanup actions

            [STATEMENT] [START 2016/02/01 23:56:09] Performing steps for cleanup

              [STATEMENT] Calling cleanup since cleanup was not ran in previous patching session.

              [STATEMENT] [START 2016/02/01 23:56:15] Performing Cleanup steps

                [STATEMENT] [START 2016/02/01 23:56:21] Running CLEANUP ddls in ddl handler table

                  [STATEMENT] [Start 2016/02/01 23:56:24] ADOP::DefaultsFileUtil::substituteDefaultsFileTokensForPatchApplTop

                    [STATEMENT] Reading defaults file D:\ora1\VISAPP\fs1\EBSapps\appl\admin\VIS\adalldefaults.txt

                    [STATEMENT] Substituting tokens ...

                    [STATEMENT] Regenerating defaults file ...

                  [STATEMENT] [End   2016/02/01 23:56:26] ADOP::DefaultsFileUtil::substituteDefaultsFileTokensForPatchApplTop

                  [STATEMENT] Log file: adop_20160201_235316.log

                  [STATEMENT] Calling: adpatch options=hotpatch,nocompiledb interactive=no console=no workers=2 restart=no abandon=yes defaultsfile=D:\ora1\VISAPP\fs1\EBSapps\appl\admin\VIS\adalldefaults.txt patchtop=D:\ora1\VISAPP\fs1\EBSapps\appl\ad\12.0.0\patch\115\driver logfile=cleanup.log driver=ucleanup.drv

                  [STATEMENT] ADPATCH Log directory: D:\ora1\VISAPP\fs_ne/EBSapps/log/adop/5/prepare_20160201_235316/VIS_srcebs/log

                  [STATEMENT] Running: adpatch options=hotpatch,nocompiledb interactive=no console=no workers=2 restart=no abandon=yes defaultsfile=D:\ora1\VISAPP\fs1\EBSapps\appl\admin\VIS\adalldefaults.txt patchtop=D:\ora1\VISAPP\fs1\EBSapps\appl\ad\12.0.0\patch\115\driver logfile=cleanup.log driver=ucleanup.drv stdin=yes

                [STATEMENT] [END   2016/02/01 23:56:57] Running CLEANUP ddls in ddl handler table

                [STATEMENT] [START 2016/02/01 23:56:59] Generating All DDL Report

                  [STATEMENT] [START 2016/02/01 23:57:00] Generating Report to display all the DDLs from ad_zd_ddl_handler

                    [STATEMENT] Report: D:\ora1\VISAPP\fs1\EBSapps\appl\ad\12.0.0/sql/ADZDALLDDLS.sql

       

       

                    [STATEMENT] Output: D:\ora1\VISAPP\fs_ne/EBSapps/log/adop/5/prepare_20160201_235316/VIS_srcebs/adzdallddls.out

       

       

                  [STATEMENT] [END   2016/02/01 23:57:07] Generating Report to display all the DDLs from ad_zd_ddl_handler

                [STATEMENT] [END   2016/02/01 23:57:09] Generating All DDL Report

                [STATEMENT] Calling cleanup in STANDARD mode

                [STATEMENT] [WARN] Cleanup may take a while. Please wait.

              [STATEMENT] [END   2016/02/01 23:57:17] Performing Cleanup steps

              [STATEMENT] [START 2016/02/01 23:57:19] Generating Post Cleanup Reports

                [STATEMENT] [START 2016/02/01 23:57:19] Generating Report to display the cleanup status

                  [STATEMENT] Report: D:\ora1\VISAPP\fs1\EBSapps\appl\ad\12.0.0/sql/ADZDCLEANUPRP.sql

       

       

                  [STATEMENT] Output: D:\ora1\VISAPP\fs_ne/EBSapps/log/adop/5/prepare_20160201_235316/VIS_srcebs/adzdcleanuprp.out

       

       

                [STATEMENT] [END   2016/02/01 23:58:12] Generating Report to display the cleanup status

              [STATEMENT] [END   2016/02/01 23:58:14] Generating Post Cleanup Reports

            [STATEMENT] [END   2016/02/01 23:58:21] Performing steps for cleanup

            [STATEMENT] No pending cleanup actions, proceeding with other steps

          [STATEMENT] [END   2016/02/01 23:58:25] Performing check to see if pending cleanup actions exist

          [STATEMENT] [START 2016/02/01 23:58:27] Deleting rows from ad_patches_tables table

          [STATEMENT] [END   2016/02/01 23:58:31] Deleting rows from ad_patches_tables table

          [STATEMENT] [START 2016/02/01 23:58:33] Performing DB Sanity checks

            [STATEMENT] [START 2016/02/01 23:58:35] Checking if edition enabled users exist

            [STATEMENT] [END   2016/02/01 23:58:39] Checking if edition enabled users exist

            [STATEMENT] [START 2016/02/01 23:58:42] Performing Check for patch service existance

            [STATEMENT] [END   2016/02/01 23:58:46] Performing Check for patch service existance

            [STATEMENT] [START 2016/02/01 23:58:49] Performing Check for Logon Trigger existence and enablement

            [STATEMENT] [END   2016/02/01 23:58:53] Performing Check for Logon Trigger existence and enablement

          [STATEMENT] [END   2016/02/01 23:58:55] Performing DB Sanity checks

          [STATEMENT] [START 2016/02/01 23:58:57] Generating Tablespace Report

            [STATEMENT] [START 2016/02/01 23:59:00] Generating report to display the status of important tablespaces. (ie. free space)

              [STATEMENT] Report: D:\ora1\VISAPP\fs1\EBSapps\appl\ad\12.0.0/sql/ADZDSHOWTS.sql

       

       

              [STATEMENT] Output: D:\ora1\VISAPP\fs_ne/EBSapps/log/adop/5/prepare_20160201_235316/VIS_srcebs/adzdshowts.out

       

       

            [STATEMENT] [END   2016/02/01 23:59:15] Generating report to display the status of important tablespaces. (ie. free space)

          [STATEMENT] [END   2016/02/01 23:59:17] Generating Tablespace Report

          [STATEMENT] [START 2016/02/01 23:59:20] Run the ADZDPATCH concurrent program

            [STATEMENT] reqid_statement: <select AD_ZD_ADOP.GET_ACTIVE_CP_REQUEST_ID() from dual>.

            [STATEMENT] sr_statement: <    declare

            result varchar2(20);

          begin

            result:=ad_zd_adop.submit_request();

          exception

                  when others then

                  update ad_adop_sessions set status='F'

                  where adop_session_id=5;

          commit;

          raise_application_error(-20001,'Error while calling ad_zd_adop.submit_request().' || sqlerrm);

          end;

      >.

            [STATEMENT] [START 2016/02/01 23:59:29] Checking status of the ADZDPATCH concurrent program

              [STATEMENT] sr_statement: <select ad_zd_adop.get_adzdpatch_status(7439650) from dual>.

              [STATEMENT] Status of ADZDPATCH:

              [STATEMENT] This request is currently processing normally. Processing began on

      01-FEB-2016 23:59:31.  No action required. You may examine the request log

      to determine its processing status. 

            [STATEMENT] [END   2016/02/01 23:59:39] Checking status of the ADZDPATCH concurrent program

            [STATEMENT] Concurrent Request Number: 7439650

          [STATEMENT] [END   2016/02/01 23:59:45] Run the ADZDPATCH concurrent program

          [STATEMENT] [START 2016/02/01 23:59:47] Generating All DDL Report

            [STATEMENT] [START 2016/02/01 23:59:48] Generating Report to display all the DDLs from ad_zd_ddl_handler

              [STATEMENT] Report: D:\ora1\VISAPP\fs1\EBSapps\appl\ad\12.0.0/sql/ADZDALLDDLS.sql

       

       

              [STATEMENT] Output: D:\ora1\VISAPP\fs_ne/EBSapps/log/adop/5/prepare_20160201_235316/VIS_srcebs/adzdallddls.out

       

       

            [STATEMENT] [END   2016/02/01 23:59:56] Generating Report to display all the DDLs from ad_zd_ddl_handler

          [STATEMENT] [END   2016/02/01 23:59:58] Generating All DDL Report

          [STATEMENT] [START 2016/02/01 23:59:58] Check and create Patch Edition (if required)

            [STATEMENT] [START 2016/02/02 00:00:01] Checking if Patch Edition already exists

              [STATEMENT] Patch Edition does not exist

              [STATEMENT] [START 2016/02/02 00:00:08] Creating Patch Edition

              [STATEMENT] [END   2016/02/02 00:00:58] Creating Patch Edition

            [STATEMENT] [END   2016/02/02 00:01:00] Checking if Patch Edition already exists

          [STATEMENT] [END   2016/02/02 00:01:03] Check and create Patch Edition (if required)

          [STATEMENT] [START 2016/02/02 00:01:05] Performing steps to synchronise the FS

            [STATEMENT] [START 2016/02/02 00:01:07] Performing steps to CLONE FS of RUN and PATCH appltops

            [STATEMENT] [END   2016/02/02 00:01:18] Performing steps to CLONE FS of RUN and PATCH appltops

            [STATEMENT] [START 2016/02/02 00:01:20] Performing steps to syncup FS of RUN and PATCH appltops

              [STATEMENT] [START 2016/02/02 00:01:23] Sync FS of RUN and PATCH

                [STATEMENT] Calling: D:\ora1\VISAPP\fs1\EBSapps\appl\ad\12.0.0/patch/115/bin/txkADOPPreparePhaseSynchronize.pl

                [STATEMENT] Output directory: D:\ora1\VISAPP\fs_ne/EBSapps/log/adop/5/prepare_20160201_235316/VIS_srcebs

              [STATEMENT] [END   2016/02/02 00:02:05] Sync FS of RUN and PATCH

            [STATEMENT] [END   2016/02/02 00:02:07] Performing steps to syncup FS of RUN and PATCH appltops

            [STATEMENT] [START 2016/02/02 00:02:09] Performing steps to CONFIG_CLONE FS of RUN and PATCH appltops

              [STATEMENT] There is a pending clone session for D:\ora1\VISAPP\fs2 file system

              [STATEMENT] [START 2016/02/02 00:02:20] Performing FS CONFIG_CLONE Steps

                [STATEMENT] [START 2016/02/02 00:02:23] CONFIG_CLONE PATCH FS with RUN FS

                  [STATEMENT] Calling: D:\ora1\VISAPP\fs1\EBSapps\appl\ad\12.0.0/patch/115/bin/txkADOPPreparePhaseSynchronize.pl

                  [STATEMENT] Output directory: D:\ora1\VISAPP\fs_ne/EBSapps/log/adop/5/prepare_20160201_235316/VIS_srcebs

                  [ERROR]     Error occurred while executing <perl D:\ora1\VISAPP\fs1\EBSapps\appl\ad\12.0.0/patch/115/bin/txkADOPPreparePhaseSynchronize.pl -contextfile=D:\ora1\VISAPP\fs1\inst\apps\VIS_srcebs\appl\admin\VIS_srcebs.xml -patchcontextfile=D:\ora1\VISAPP\fs2\inst\apps\VIS_srcebs\appl\admin\VIS_srcebs.xml -promptmsg=hide -console=off -mode=migrate -sessionid=5 -timestamp=20160201_235316 -outdir=D:\ora1\VISAPP\fs_ne/EBSapps/log/adop/5/prepare_20160201_235316/VIS_srcebs>

                  [ERROR]     Error occurred while CONFIG_CLONE PATCH FS with RUN FS using command: <perl D:\ora1\VISAPP\fs1\EBSapps\appl\ad\12.0.0/patch/115/bin/txkADOPPreparePhaseSynchronize.pl -contextfile=D:\ora1\VISAPP\fs1\inst\apps\VIS_srcebs\appl\admin\VIS_srcebs.xml -patchcontextfile=D:\ora1\VISAPP\fs2\inst\apps\VIS_srcebs\appl\admin\VIS_srcebs.xml -promptmsg=hide -console=off -mode=migrate -sessionid=5 -timestamp=20160201_235316 -outdir=D:\ora1\VISAPP\fs_ne/EBSapps/log/adop/5/prepare_20160201_235316/VIS_srcebs>.

                  [STATEMENT] fs_clone phase completed with errors/warnings. Please check logfiles

                  [ERROR]     Error while runPendingConfigClone sub-routine is called

                  [STATEMENT] [START 2016/02/02 00:02:57] Check and Stop Patch Admin Server

                    [STATEMENT]         select count(1) from ad_adop_session_patches

              where applied_file_system_base='D:\ora1\VISAPP\fs1' and

              patch_file_system_base='D:\ora1\VISAPP\fs2' and

              bug_number IN ('CLONE','CONFIG_CLONE') and

              status IN ('N','F')

       

       

                    [STATEMENT] There is a pending clone session for D:\ora1\VISAPP\fs2.

                    [STATEMENT] Skipping Check and Stop Patch Admin Server Step.

                  [STATEMENT] [END   2016/02/02 00:03:07] Check and Stop Patch Admin Server

                  [STATEMENT] Prepare phase completed with errors/warnings. Please check logfiles

                  [STATEMENT] SQL Statement:       update ad_adop_sessions

              set status='F'

               where adop_session_id = 5 and appltop_id = 2045 and node_name='srcebs'

       

       

                  [STATEMENT] SQL Statement:         update ad_adop_sessions

              set prepare_end_date=to_date('02-02-2016 00:03:20','DD-MM-YYYY HH24:MI:SS')

              where  adop_session_id=5

       

       

                  [STATEMENT] PREPARE Phase END TIME: 02-02-2016 00:03:20

                  [STATEMENT] [START 2016/02/02 00:03:26] Unlocking sessions table

                    [STATEMENT] SQL stmt: <       begin

                AD_ZD_ADOP.UNLOCK_SESSIONS_TABLE('srcebs',60,2);

             end;

      >

       

       

                  [STATEMENT] [END   2016/02/02 00:03:29] Unlocking sessions table

                  [STATEMENT] SQL Statement:       update ad_adop_sessions

              set status='F'

               where adop_session_id = 5 and appltop_id = 2045 and node_name='srcebs'

       

       

                  [STATEMENT] Log file: D:\ora1\VISAPP\fs_ne/EBSapps/log/adop/5/adop_20160201_235316.log