Solved: Re: Error when upgrading from 10.0 to 10.5

soloo2014 · ‎01-22-2019

i was trying to upgrade the cucm for one of my clients from 10.0 to 10.5 and i got some errors

01/22/2019 18:26:48 component_install|(CAPTURE) enablenotify dsn[DSN=ccm_super;Servername=cucm_pu_ccm10_5_2_18900_15]|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) enablenotify rc[0]|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) installFull rc[1]|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) installdb Failure [-i] 1|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) -----------------------------------|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) installdb_l2.log.err file contents:|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) 18:24:14.549 |*ERROR* Error executing "insert into phonebutton (buttonnum,fkphonetemplate,isfixedfeature,label,paramn1,paramn2,pkid,resettoggle,tkfeature,tkreset) values (1,'0d68db20-e1be-b913-b491-e8628c40eed7','T','Line',1,0,'e98de5b2-a35e-44f3-bd48-59e0ca9a2e8f','F',9,2): [Informix][Informix ODBC Driver][Informix]Missing key in referenced table for referential constraint (informix.fk_phonebutton_fkphonetemplate).|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) 18:24:14.552 |*ERROR* Error executing "insert into phonebutton (buttonnum,fkphonetemplate,isfixedfeature,label,paramn1,paramn2,pkid,resettoggle,tkfeature,tkreset) values (2,'0d68db20-e1be-b913-b491-e8628c40eed7','F','Line',2,0,'0c2fdf50-3031-4e40-90d9-4bedafe828bb','F',9,2): [Informix][Informix ODBC Driver][Informix]Missing key in referenced table for referential constraint (informix.fk_phonebutton_fkphonetemplate).|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) 18:24:14.553 |*ERROR* Error executing "insert into phonebutton (buttonnum,fkphonetemplate,isfixedfeature,label,paramn1,paramn2,pkid,resettoggle,tkfeature,tkreset) values (3,'0d68db20-e1be-b913-b491-e8628c40eed7','F','Line',3,0,'ff90c943-da7a-410a-b9f6-49408dd130f4','F',9,2): [Informix][Informix ODBC Driver][Informix]Missing key in referenced table for referential constrainanalogaccess.csv|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) analogaccessport.csv|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) applicationuserdirgroupmap.csv|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) appserver.csv|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) appserverinfo.csv|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) blfspeeddial.csv|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) callforwarddynamic.csv|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) callforwardhistorydynamic.csv|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) callingsearchspace.csv|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) callingsearchspacemember.csv|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) callmanager.csv|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) callmanagergroup.csv|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) callmanagergroupmember.csv|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) certificate.csv|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) certificatehashmap.csv|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) certificateprocessnodemap.csv|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) certificateservicecertificatemap.csv|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) certificatetrustrolemap.csv|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) codeclist.csv|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) codeclistmember.csv|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) commonphoneconfig.csv|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) commonphoneconfigxml.csv|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) credential.csv|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) credentialdynamic.csv|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) credentialhistory.csv|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) credentialpolicy.csv|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) credentialpolicydefault.csv|<LVL::Debug>

01/22/2019 18:26:48 component_install|(CAPTURE) voicemessagingprofile.csv|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) t (informix.fk_phonebutton_fkphonetemplate).|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) 18:24:14.554 |*ERROR* Error executing "insert into phonebutton (buttonnum,fkphonetemplate,isfixedfeature,label,paramn1,paramn2,pkid,resettoggle,tkfeature,tkreset) values (4,'0d68db20-e1be-b913-b491-e8628c40eed7','F','Line',4,0,'367d4158-eb78-478e-86bf-143d6488e05a','F',9,2): [Informix][Informix ODBC Driver][Informix]Missing key in referenced table for referential constraint (informix.fk_phonebutton_fkphonetemplate).|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) 18:24:14.555 |*ERROR* Error executing "insert into phonebutton (buttonnum,fkphonetemplate,isfixedfeature,label,paramn1,paramn2,pkid,resettoggle,tkfeature,tkreset) values (5,'0d68db20-e1be-b913-b491-e8628c40eed7','T','Speed Dial',1,0,'79f2c22b-4437-40fd-afab-83361feeafd4','F',2,2): [Informix][Informix ODBC Driver][Informix]Missing key in referenced table for referential constraint (informix.fk_phonebutton_fkphonetemplate).|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) 18:24:14.556 |*ERROR* Error executing "insert into phonebutton (buttonnum,fkphonetemplate,isfixedfeature,label,paramn1,paramn2,pkid,resettoggle,tkfeature,tkreset) values (6,'0d68db20-e1be-b913-b491-e8628c40eed7','T','Speed Dial',2,0,'9c913384-3d59-4afc-93cd-5d3f0b77496e','F',2,2): [Informix][Informix ODBC Driver][Informix]Missing key in referenced table for referential constraint (informix.fk_phonebutton_fkphonetemplate).|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) 18:24:14.557 |*ERROR* Error executing "insert into phonebutton (buttonnum,fkphonetemplate,isfixedfeature,label,paramn1,paramn2,pkid,resettoggle,tkfeature,tkreset) values (7,'0d68db20-e1be-b913-b491-e8628c40eed7','T','Speed Dial',3,0,'947bbde2-cbde-4722-a13d-7d98871d5e90','F',2,2): [Informix][Informix ODBC Driver][Informix]Missing key in referenced table for referential constraint (informix.fk_phonebutton_fkphonetemplate).|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) 18:24:14.558 |*ERROR* Error executing "insert into phonebutton (buttonnum,fkphonetemplate,isfixedfeature,label,paramn1,paramn2,pkid,resettoggle,tkfeature,tkreset) values (8,'0d68db20-e1be-b913-b491-e8628c40eed7','T','Speed Dial',4,0,'14f553ec-d1e8-4bbd-bb04-7fa9de7afb08','F',2,2): [Informix][Informix ODBC Driver][Informix]Missing key in referenced table for referential constraint (informix.fk_phonebutton_fkphonetemplate).|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) 18:24:14.559 |*ERROR* Error executing "insert into phonebutton (buttonnum,fkphonetemplate,isfixedfeature,label,paramn1,paramn2,pkid,resettoggle,tkfeature,tkreset) values (9,'0d68db20-e1be-b913-b491-e8628c40eed7','T','Speed Dial',5,0,'a6fee5d3-afd1-406e-8251-0484281ca080','F',2,2): [Informix][Informix ODBC Driver][Informix]Missing key in referenced table for referential constraint (informix.fk_phonebutton_fkphonetemplate).|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) 18:24:14.559 |*ERROR* Error executing "insert into phonebutton (buttonnum,fkphonetemplate,isfixedfeature,label,paramn1,paramn2,pkid,resettoggle,tkfeature,tkreset) values (10,'0d68db20-e1be-b913-b491-e8628c40eed7','T','Speed Dial',6,0,'dad48938-50f2-4d77-9f4e-a768b5504220','F',2,2): [Informix][Informix ODBC Driver][Informix]Missing key in referenced table for referential constraint (informix.fk_phonebutton_fkphonetemplate).|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) 18:24:36.334 | installFull *ERROR* Prior Cancel or Error Processing convert5to6()|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) |<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) |<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) -----------------------------------|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) ------------------------------|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) psqlx_output.log.err contents:|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) |<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) |<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) ------------------------------|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) Started onshutdown.sh|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) Setting default timeout to 30 seconds.|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) |<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) ********************************************************|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) !!! WARNING !!!|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) |<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) THE onshutdown SCRIPT WILL TRY TO SHUTDOWN AND TERMINATE|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) THIS IDS INSTANCE cucm_pu_ccm10_5_2_18900_15 UNCONDITIONALLY!|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) ********************************************************|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) |<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) Hit Ctrl-C if you wish to abort this operation!|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) Waiting for 10 seconds before continuing...|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) Attempting to shutdown cucm_pu_ccm10_5_2_18900_15 in 30 seconds...|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) Warning: ONCONFIG /usr/local/cm/db/informix/etc/onconfig.ccmb is not owned by user informix (uid=512).|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) Finished executing /usr/local/cm/db/informix/bin/onmode.|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) Finished onshutdown.sh|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) shared memory not initialized for INFORMIXSERVER 'cucm_pu_ccm10_5_2_18900_15'|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) 18:26:30 Checkpoint Statistics - Avg. Txn Block Time 0.000, # Txns blocked 1, Plog used 1502, Llog used 165846|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) |<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) 18:26:47 Checkpoint Completed: duration was 0 seconds.|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) 18:26:47 Tue Jan 22 - loguniq 13, logpos 0x135c9278, timestamp: 0x24a958 Interval: 39|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) |<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) 18:26:47 Maximum server connections 31 |<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) 18:26:47 Checkpoint Statistics - Avg. Txn Block Time 0.000, # Txns blocked 0, Plog used 1580, Llog used 1295|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) |<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) 18:26:48 IBM Informix Dynamic Server Stopped.|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) |<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) su -c 'if [ -f /usr/local/cm/db/informix/local/ids.env ]; then source /usr/local/cm/db/informix/local/ids.env l2; fi ; onshutdown.sh' - informix|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) source /usr/local/cm/db/informix/local/ids.env l2 ; onstat -|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) Tue Jan 22 18:26:48 2019 cm-dbl-install.installdb DEBUG: <--|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) Tue Jan 22 18:26:48 2019 cm-dbl-install.doL2_migrateDB DEBUG: <--|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) Tue Jan 22 18:26:48 2019 cm-dbl-install.doL2Post DEBUG: <--|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) Tue Jan 22 18:26:48 2019 cm-dbl-install.doActionL2Update DEBUG: <--|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) Tue Jan 22 18:26:48 2019 cm-dbl-install DEBUG: cm-dbl-install [1]|<LVL::Debug>
01/22/2019 18:26:48 component_install|(CAPTURE) Tue Jan 22 18:26:48 2019 cm-dbl-install DEBUG: <--|<LVL::Debug>
01/22/2019 18:26:48 component_install|File:/opt/cisco/install/bin/component_install:874, Function: execute_shell_cmd(), /usr/local/cm/script/cm-dbl-install L2 PostInstall 10.5.2.18900-15 10.0.1.10000-24 /usr/local/cm/ /partB/usr/local/cm/ /common/log/install/capture.txt failed (1)|<LVL::Error>
01/22/2019 18:26:48 appmanager.sh|Internal Error, File:/usr/local/bin/base_scripts/appmanager.sh:198, Function: upgrade(), failed to upgrade infrastructure_post components|<LVL::Critical>
01/22/2019 18:26:48 post_upgrade|File:/opt/cisco/install/bin/post_upgrade:132, Function: install_applications(), /usr/local/bin/base_scripts/appmanager.sh -upgrade -basepath /common/rpm-archive/10.5.2.18900-15 -logfile /common/log/install/install_log_2019-01-22.17.36.34.log -resultfile /common/log/install/upgrade-results.xml failed (1)|<LVL::Error>
01/22/2019 18:26:48 upgrade_manager.sh|File:/common/download/10.5.2.18900-15/upgrade_manager.sh:1835, Function: main(), Applications upgrade failed|<LVL::Error>
01/22/2019 18:26:48 upgrade_manager.sh|Final exit processing with result 1|<LVL::Info>
01/22/2019 18:26:49 upgrade_manager.sh|Exiting with result 1|<LVL::Info>
01/22/2019 18:26:49 upgrade_manager.sh|Invalidate upgrade partition|<LVL::Info>
01/22/2019 18:26:49 upgrade_manager.sh|Clean up rpm any stale __db. files in rpmdb on the to side|<LVL::Debug>
01/22/2019 18:26:49 upgrade_manager.sh|Removing any master RPM from /partB|<LVL::Debug>
01/22/2019 18:26:49 upgrade_manager.sh|Removing any /partB/etc/opt/cisco/install.conf|<LVL::Debug>
01/22/2019 18:26:49 upgrade_manager.sh|Invalidate any product configuration file|<LVL::Debug>
01/22/2019 18:26:49 upgrade_manager.sh|Setting product and deployment version attributes in /partB/etc/opt/cisco/install/conf/callmanager_product.conf to 0.0.0.0000-0000|<LVL::Debug>
01/22/2019 18:26:49 upgrade_manager.sh|Calling /common/download/10.5.2.18900-15/Cisco/bin/prepare_grub --phase L2 --action recovery --basepath /mnt/source/Cisco --logfile /common/log/install/capture.txt|<LVL::Debug>
01/22/2019 18:26:50 upgrade_manager.sh|(CAPTURE) [19/01/22_18:26:49] prepare_grub: The BOOT_DIR is: //boot |<LVL::Debug>
01/22/2019 18:26:50 upgrade_manager.sh|(CAPTURE) [19/01/22_18:26:49] prepare_grub: The GRUB_DIR is: /grub |<LVL::Debug>
01/22/2019 18:26:50 upgrade_manager.sh|(CAPTURE) [19/01/22_18:26:49] prepare_grub: The SYS_DIR is: //sys |<LVL::Debug>
01/22/2019 18:26:50 upgrade_manager.sh|(CAPTURE) [19/01/22_18:26:49] prepare_grub: The GRUB_CONF is: /grub/boot/grub/grub.conf |<LVL::Debug>
01/22/2019 18:26:50 upgrade_manager.sh|(CAPTURE) [19/01/22_18:26:49] prepare_grub: The RECOVERY_GRUB_CONF is: /grub/boot/grub/grub.conf.recovery |<LVL::Debug>
01/22/2019 18:26:50 upgrade_manager.sh|(CAPTURE) [19/01/22_18:26:49] prepare_grub: The COMMON directory is : /common |<LVL::Debug>
01/22/2019 18:26:50 upgrade_manager.sh|(CAPTURE) [19/01/22_18:26:49] prepare_grub: Preparing grub for phase: l2!|<LVL::Debug>
01/22/2019 18:26:50 upgrade_manager.sh|(CAPTURE) [19/01/22_18:26:49] prepare_grub: Setting up GRUB for l2 for recovery action|<LVL::Debug>
01/22/2019 18:26:50 upgrade_manager.sh|(CAPTURE) shell-init: error retrieving current directory: getcwd: cannot access parent directories: No such file or directory|<LVL::Debug>
01/22/2019 18:26:50 upgrade_manager.sh|(CAPTURE) Mail notification cancelled - smtp server address for email not found! [/common/cisco/platformConfig.xml]|<LVL::Debug>
01/22/2019 18:26:50 upgrade_manager.sh|IOWAIT monitor stopped|<LVL::Info>
01/22/2019 18:26:50 upgrade_manager.sh|Exit processing with result 0|<LVL::Info>
01/22/2019 18:26:50 preflight|Starting preflight|<LVL::Info>
01/22/2019 18:26:50 preflight|Validate preflight data|<LVL::Info>
01/22/2019 18:26:50 preflight|Preflight data validation complete|<LVL::Info>
01/22/2019 18:26:50 preflight|Determine upgrade type|<LVL::Info>
01/22/2019 18:26:51 preflight|Active RH version = 6Server|<LVL::Debug>
01/22/2019 18:26:51 preflight|New os version(xml) = 6Server|<LVL::Debug>
01/22/2019 18:26:51 preflight|Upgrade type = L2|<LVL::Debug>
01/22/2019 18:26:51 preflight|Obtain product type|<LVL::Info>

01/22/2019 18:26:52 preflight|Obtained product type CallManager|<LVL::Info>
01/22/2019 18:26:52 preflight|Processing preflight cleanup mode|<LVL::Info>
01/22/2019 18:26:52 preflight|Clear preflight cancel utility|<LVL::Info>
01/22/2019 18:26:52 preflight|Removing /common/cancel_upgrade/preflight_cancel|<LVL::Debug>
01/22/2019 18:26:52 preflight|Execute cleanup preflight commands for CallManager:L2 from /mnt/source/Cisco/install/preflight.xml|<LVL::Info>
01/22/2019 18:26:52 preflight|Found 7 preflight commands to execute|<LVL::Debug>
01/22/2019 18:26:52 preflight|Execute preflight command (1/7): rm -rf /usr/local/platform/conf/dynamicConfig.xml|<LVL::Debug>
01/22/2019 18:26:52 preflight|Preflight command returns 0|<LVL::Debug>
01/22/2019 18:26:52 preflight|Execute preflight command (2/7): cp -f /common/preflight/CM/L2/dynamicConfig.xml /usr/local/platform/conf/dynamicConfig.xml|<LVL::Debug>
01/22/2019 18:26:52 preflight|Preflight command returns 1|<LVL::Debug>
01/22/2019 18:26:52 preflight|(CAPTURE) cp: cannot stat `/common/preflight/CM/L2/dynamicConfig.xml': No such file or directory|<LVL::Debug>
01/22/2019 18:26:52 preflight|Preflight command did not complete (1). Continuing command processing...|<LVL::Warn>
01/22/2019 18:26:52 preflight|Execute preflight command (3/7): rm -rf /usr/local/thirdparty/jakarta-tomcat/webapps/cmplatform/WEB-INF/pages/install/dynamic.jsp|<LVL::Debug>
01/22/2019 18:26:52 preflight|Preflight command returns 0|<LVL::Debug>
01/22/2019 18:26:52 preflight|Execute preflight command (4/7): cp -f /common/preflight/CM/L2/dynamic.jsp /usr/local/thirdparty/jakarta-tomcat/webapps/cmplatform/WEB-INF/pages/install/dynamic.jsp|<LVL::Debug>
01/22/2019 18:26:52 preflight|Preflight command returns 0|<LVL::Debug>
01/22/2019 18:26:52 preflight|Execute preflight command (5/7): rm -rf /usr/local/thirdparty/jakarta-tomcat/webapps/cmplatform/WEB-INF/pages/install/install-confirm-content.jsp|<LVL::Debug>
01/22/2019 18:26:52 preflight|Preflight command returns 0|<LVL::Debug>
01/22/2019 18:26:52 preflight|Execute preflight command (6/7): cp -f /common/preflight/CM/L2/install-confirm-content.jsp /usr/local/thirdparty/jakarta-tomcat/webapps/cmplatform/WEB-INF/pages/install/install-confirm-content.jsp|<LVL::Debug>
01/22/2019 18:26:52 preflight|Preflight command returns 0|<LVL::Debug>
01/22/2019 18:26:52 preflight|Execute preflight command (7/7): rm -rf /common/preflight/CM/L2|<LVL::Debug>
01/22/2019 18:26:52 preflight|Preflight command returns 0|<LVL::Debug>
01/22/2019 18:26:52 upgrade_manager.sh|Cleanup RAM disk|<LVL::Info>
01/22/2019 18:26:52 upgrade_manager.sh|Unmount /common/mnt/updates_img|<LVL::Debug>
01/22/2019 18:26:52 upgrade_manager.sh|Cleanup mounted DVD or ISO|<LVL::Info>
01/22/2019 18:26:53 upgrade_manager.sh|Found mount point "/mnt/source" for source filesystem /common/download/UCSInstall_UCOS_10.5.2.18900-15.sgn.iso|<LVL::Debug>
01/22/2019 18:26:53 upgrade_manager.sh|Unmount /mnt/source|<LVL::Debug>
01/22/2019 18:26:53 upgrade_manager.sh|Remove /common/download/UCSInstall_UCOS_10.5.2.18900-15.sgn.iso|<LVL::Info>
01/22/2019 18:26:53 upgrade_manager.sh|Refresh CLI product properties cache|<LVL::Info>
01/22/2019 18:26:53 upgrade_manager.sh|Refresh hardware cache|<LVL::Info>
01/22/2019 18:26:53 ServerApiManager|INITIALIZE: sam_legacy_mode_init=1|<LVL::Debug>

01/22/2019 18:26:55 ServerApiManager|INITIALIZE: sam_hssi_mode_init=0|<LVL::Debug>

01/22/2019 18:26:59 upgrade_manager.sh|Cleanup exiting - Cached Data: [Vendor= VMware, Inc.
HWModel=VMware
CPUCount=2
CPUType= Intel(R) Xeon(R) CPU E5-2609 0 @ 2.40GHz
CPUSpeed=2400
MEMSize=4096
BIOSVer=PhoenixTechnologiesLTD 6.00 06/22/2012
ObjectId=1.3.6.1.4.1.9.1.1348
OSVersion=UCOS 6.0.0.0-2.i386
SerialNumber= VMware-56 4d c1 e8 94 3a fd d5-8f f9 e7 96 8b 61 12 a9
VendorOID=1.3.6.1.4.1.674
areDisksAligned=0]|<LVL::Info>
01/22/2019 18:26:59 upgrade_install.sh|Cleaning up download...|<LVL::Info>
01/22/2019 18:26:59 upgrade_install.sh|Cleanup upgrade source area.|<LVL::Info>
01/22/2019 18:26:59 upgrade_install.sh|Ejecting DVD (/dev/sda1)|<LVL::Debug>
01/22/2019 18:26:59 upgrade_install.sh|Removing /common/download/10.5.2.18900-15|<LVL::Info>
01/22/2019 18:26:59 upgrade_install.sh|Removing /common/cisco|<LVL::Info>
01/22/2019 18:27:00 upgrade_install.sh|Started auditd...|<LVL::Info>
01/22/2019 18:27:00 upgrade_install.sh|Started setroubleshoot...|<LVL::Info>

01/22/2019 18:27:00 upgrade_install.sh|Changed selinux mode to enforcing|<LVL::Info>
01/22/2019 18:27:00 upgrade_install.sh|Cleaning up rpm_archive...|<LVL::Info>
01/22/2019 18:27:00 upgrade_install.sh|Removing /common/rpm-archive/10.5.2.18900-15|<LVL::Info>
01/22/2019 18:27:01 upgrade_install.sh|File:/usr/local/bin/base_scripts/upgrade_install.sh:604, Function: main(), Upgrade Failed -- (1)|<LVL::Error>
01/22/2019 18:27:01 upgrade_install.sh|Parse argument status=upgrade.stage.error|<LVL::Debug>
01/22/2019 18:27:01 upgrade_install.sh|_set_upgrade_status_attribute: status set to upgrade.stage.error|<LVL::Debug>
01/22/2019 18:27:01 upgrade_install.sh|is_upgrade_lock_available: Upgrade lock is not available.|<LVL::Debug>
01/22/2019 18:27:01 upgrade_install.sh|is_upgrade_in_progress: Already locked by this process (pid: 6349).|<LVL::Debug>
01/22/2019 18:27:01 upgrade_install.sh|release_upgrade_lock: Releasing lock (pid: 6349)|<LVL::Debug>

after searching the community i found this thread similar to my case

https://community.cisco.com/t5/unified-communications/cucm-9-1-2-upgrade-to-11-0-1a-su1-failing/td-p/2930737

but in his scenario it was clear that DX630 was the issue , in my case i just receive 0d68db20-e1be-b913-b491-e8628c40eed7 and no device name or description

thanks in advance

soloo2014 · ‎01-26-2019

for any one with a similar situation i have managed to complete the upgrade .

i did a couple of things:

Disabled Extension Mobility service.

Removed backup device if any.

then i checked the error message and it seemed related to a phone button template

01/22/2019 18:26:48 component_install|(CAPTURE) 18:24:14.554 |*ERROR* Error executing "insert into phonebutton (buttonnum,fkphonetemplate,isfixedfeature,label,paramn1,paramn2,pkid,resettoggle,tkfeature,tkreset) values (4,'0d68db20-e1be-b913-b491-e8628c40eed7','F','Line',4,0,'367d4158-eb78-478e-86bf-143d6488e05a','F',9,2): [Informix][Informix ODBC Driver][Informix]Missing key in referenced table for referential constraint (informix.fk_phonebutton_fkphonetemplate).|<LVL::Debug>

also i noticed that this specific template had 4 lines , so i searched for a template with 4 lines and there was only one (this setup is small with less than 50 phones so it was not hard to search through them all) the template was a custom template that is not even used for any phone , i deleted this template.

after doing those changes the upgrade process went smoothly without any error

View solution in original post

soloo2014 · ‎01-26-2019