top of page

Experienced Technology Product Manager adept at steering success throughout the entire product lifecycle, from conceptualization to market delivery. Proficient in market analysis, strategic planning, and effective team leadership, utilizing data-driven approaches for ongoing enhancements.

  • Twitter
  • LinkedIn
White Background

Behind the scenes: vRLCM upgrade 8.1 to 8.2

I always had a passion for reading logs and understanding what really happens in the background when a user clicks or performs an action through UI.

Yesterday, I did a blog about vRSLCM upgrade from 8.1 to 8.2 through UI, let me decipher what happens in the background when you upgrade.


The only log you would need to check is

/var/log/vrlcm/vmware_vrlcm.log
/var/log/bootstrap/preupdate.log
/var/log/bootstrap/postupdate.log


Just before you start an upgrade you would check if the upgrade is actually available or not.



2020-10-08 01:30:53.274 INFO  [http-nio-8080-exec-8] c.v.v.l.u.ShellExecutor -  -- Executing shell command: /opt/vmware/bin/vamicli update --check
2020-10-08 01:30:53.275 INFO  [http-nio-8080-exec-8] c.v.v.l.u.ProcessUtil -  -- Execute /opt/vmware/bin/vamicli
2020-10-08 01:30:55.475 INFO  [http-nio-8080-exec-8] c.v.v.l.u.ShellExecutor -  -- Result: [Checking for available updates, this process can take a few minutes...
..
Available Updates - 
   8.2.0.23 Build 16982087].

Once you have verified that the upgrade is available and you trigger an upgrade there is a Postgres backup taken in the background



2020-10-08 01:34:16.418 INFO  [http-nio-8080-exec-1] c.v.v.l.u.ShellExecutor -  -- Executing shell command: /etc/vlcm/upgrade-va
2020-10-08 01:34:16.419 INFO  [http-nio-8080-exec-1] c.v.v.l.u.ProcessUtil -  -- Execute /etc/vlcm/upgrade-va
2020-10-08 01:34:21.326 INFO  [http-nio-8080-exec-1] c.v.v.l.u.ShellExecutor -  -- Result: [taking postgres backup before starting upgrade
creating new backup file
current file does not exist
creating a deamon process
Successfully triggered upgrade.].

There will be a check which would be performed to see if an upgrade is already running


2020-10-08 01:34:21.383 INFO  [http-nio-8080-exec-6] c.v.v.l.u.ShellExecutor -  -- Executing shell command: /opt/vmware/bin/vamicli update --progress --detail
2020-10-08 01:34:21.385 INFO  [http-nio-8080-exec-6] c.v.v.l.u.ProcessUtil -  -- Execute /opt/vmware/bin/vamicli
2020-10-08 01:34:21.588 INFO  [http-nio-8080-exec-6] c.v.v.l.u.ShellExecutor -  -- Result: [No update instance is running.].

At this point, we start downloading packages needed for upgrade




2020-10-08 01:34:25.647 INFO  [http-nio-8080-exec-4] c.v.v.l.u.ProcessUtil -  -- Execute /opt/vmware/bin/vamicli
2020-10-08 01:34:25.947 INFO  [http-nio-8080-exec-4] c.v.v.l.u.ShellExecutor -  -- Result: [4/92 packages downloaded.
Downloading...
PyYAML  Done
VMware-Log-Insight-Agent  Done
audit Done
bindutils Done
blackstone  
bzip2 
bzip2-libs  
ca-certificates 
*
*
*
vmware-studio-appliance-config  
vmware-studio-init  
which 
zip].
2020-10-08 01:34:41.643 INFO  [http-nio-8080-exec-3] c.v.v.l.u.ShellExecutor -  -- Executing shell command: /opt/vmware/bin/vamicli update --progress --detail
2020-10-08 01:34:41.645 INFO  [http-nio-8080-exec-3] c.v.v.l.u.ProcessUtil -  -- Execute /opt/vmware/bin/vamicli
2020-10-08 01:34:41.814 INFO  [http-nio-8080-exec-3] c.v.v.l.u.ShellExecutor -  -- Result: [69/92 packages downloaded.
Downloading...
PyYAML  Done
VMware-Log-Insight-Agent  Done
*
*
*
vmware-studio-appliance-config  
vmware-studio-init  
which 
zip].
*
*
*

2020-10-08 01:38:37.939 INFO  [http-nio-8080-exec-10] c.v.v.l.u.ShellExecutor -  -- Executing shell command: /opt/vmware/bin/vamicli update --progress --detail
2020-10-08 01:38:37.942 INFO  [http-nio-8080-exec-10] c.v.v.l.u.ProcessUtil -  -- Execute /opt/vmware/bin/vamicli
2020-10-08 01:38:38.072 INFO  [http-nio-8080-exec-10] c.v.v.l.u.ShellExecutor -  -- Result: [92 packages have been downloaded.


Once all the packages have been downloaded it would start installing also it triggers /etc/bootstrap/postupdate.d/*.* scripts as well



2020-10-08 01:38:39 Main bootstrap postupdate started

2020-10-08 01:38:39 /etc/bootstrap/postupdate.d/00-00-rpm-status-check starting...
Check if RPM packages are performed properly...
2020-10-08 01:38:39 /etc/bootstrap/postupdate.d/00-00-rpm-status-check done, succeeded.
2020-10-08 01:38:39 /etc/bootstrap/postupdate.d/20-disable-password-expiration starting...
2020-10-08 01:38:39 /etc/bootstrap/postupdate.d/20-disable-password-expiration done, succeeded.
2020-10-08 01:38:39 /etc/bootstrap/postupdate.d/21-update-ulimit starting...
Updating ulimit...
2020-10-08 01:38:39 /etc/bootstrap/postupdate.d/21-update-ulimit done, succeeded.

2020-10-08 01:38:39 /etc/bootstrap/postupdate.d/23-postgres-conf starting...
checking whether autovaccum configurations already exists.
2020-10-08 01:38:39 /etc/bootstrap/postupdate.d/23-postgres-conf done, succeeded.
2020-10-08 01:38:39 /etc/bootstrap/postupdate.d/25-start-services starting...
+ set -e
+ echo 'Reboot not required so starting all service.'
Reboot not required so starting all service.
+ systemctl daemon-reload
+ systemctl restart vpostgres
+ touch /var/log/bootstrap/reboot-required
+ systemctl restart vrlcm-server
+ systemctl restart blackstone-spring
+ cp -r /var/lib/vrlcm/nginx.conf /etc/nginx/
+ cp -r /var/lib/vrlcm/ssl.conf /etc/nginx/
+ systemctl reload nginx
+ rm /var/lib/vrlcm/SUCCESS
+ rm -rf /tmp/dlfRepo
+ [[ ! -f /etc/triggerLicenseUpdate ]]
+ touch /etc/triggerLicenseUpdate
+ [[ -f /var/log/vrlcm/status.txt ]]
+ rm -rf /var/log/vrlcm/status.txt_backup
+ cp -r /var/log/vrlcm/status.txt /var/log/vrlcm/status.txt_backup
+ rm -rf /var/log/vrlcm/status.txt
+ /var/lib/vrlcm/populate.sh
  % Total    % Received % Xferd  Average Speed   Time    Time     Time  Current
                                 Dload  Upload   Total   Spent    Left  Speed
^M  0     0    0     0    0     0      0      0 --:--:-- --:--:-- --:--:--     0^M  0     0    0     0    0     0      0      0 --:--:--  0:00:01 --:--:--     0^M  0     0    0     0    0     0      0      0 --:--:--  0:00:01 --:--:--     0^M  0     0    0     0    0     0      0      0 --:--:--  0:00:01 --:--:--     0
Traceback (most recent call last):
  File "/usr/lib/python2.7/logging/__init__.py", line 868, in emit
    msg = self.format(record)
  File "/usr/lib/python2.7/logging/__init__.py", line 741, in format
    return fmt.format(record)
  File "/usr/lib/python2.7/logging/__init__.py", line 465, in format
    record.message = record.getMessage()
  File "/usr/lib/python2.7/logging/__init__.py", line 329, in getMessage
    msg = msg % self.args
TypeError: not all arguments converted during string formatting
Logged from file insert.py, line 302
+ [[ -f /var/lib/vrlcm/SUCCESS ]]
+ echo 'Creating INPROGRESS to block UI from loading...'
Creating INPROGRESS to block UI from loading...
+ rm -rf /var/lib/vrlcm/SUCCESS
+ touch /var/lib/vrlcm/INPROGRESS
+ exit 0

After the above post-update tasks installation begins


2020-10-08 01:38:47.237 INFO  [http-nio-8080-exec-2] c.v.v.l.u.ShellExecutor -  -- Result: [92 packages have been downloaded.
Installing...].

2020-10-08 01:38:56.103 INFO  [background-preinit] o.h.v.i.u.Version -  -- HV000001: Hibernate Validator 6.0.20.Final
*
*
*
2020-10-08 01:41:39.490 INFO  [main] c.v.v.l.a.c.EngineInitializer -  -- Checking for IN_PROGRESS EVENTS
2020-10-08 01:41:39.592 INFO  [scheduling-1] c.v.v.l.a.g.c.CleanupProcessor -  -- Initiating clean-up and purging.
2020-10-08 01:41:41.017 INFO  [main] c.v.v.l.a.c.EngineInitializer -  -- IN_PROGRESS EVENTS Reset is Completed
2020-10-08 01:41:41.020 INFO  [main] c.v.v.v.s.c.DefaultUserInitializer -  -- Initializing default users and roles
2020-10-08 01:41:41.022 INFO  [main] c.v.v.l.c.l.MaskingPrintStream -  -- * SYSOUT/SYSERR CAPTURED:  -- Initializing default users and roles
2020-10-08 01:41:41.118 INFO  [main] c.v.v.l.c.s.a.s.i.AuthNRoleServiceImpl -  -- Existing Role name : VCF Role
2020-10-08 01:41:41.121 INFO  [main] c.v.v.l.c.s.a.s.i.AuthNRoleServiceImpl -  -- Existing Role name : Content Developer
2020-10-08 01:41:41.122 INFO  [main] c.v.v.l.c.s.a.s.i.AuthNRoleServiceImpl -  -- Existing Role name : LCM Admin
2020-10-08 01:41:41.129 INFO  [main] c.v.v.l.c.s.a.s.i.AuthNRoleServiceImpl -  -- Existing Role name : LCM Service Admin
2020-10-08 01:41:41.131 INFO  [main] c.v.v.l.c.s.a.s.i.AuthNRoleServiceImpl -  -- Existing Role name : Content Release Manager
2020-10-08 01:41:41.132 INFO  [main] c.v.v.l.c.s.a.s.i.AuthNRoleServiceImpl -  -- Existing Role name : LCM Support User
2020-10-08 01:41:41.134 INFO  [main] c.v.v.l.c.s.a.s.i.AuthNRoleServiceImpl -  -- Existing Role name : LCM Cloud Admin
2020-10-08 01:41:41.142 INFO  [main] c.v.v.l.c.s.a.s.i.AuthNRoleServiceImpl -  -- Existing Role name : Certificate Administrator
2020-10-08 01:41:41.217 INFO  [main] c.v.v.l.c.s.a.s.i.AuthNUserServiceImpl -  -- Existing Local Username : serviceadmin@local
2020-10-08 01:41:41.219 INFO  [main] c.v.v.l.c.s.a.s.i.AuthNUserServiceImpl -  -- Existing Local Username : vcfadmin@local
2020-10-08 01:41:41.220 INFO  [main] c.v.v.l.c.s.a.s.i.AuthNUserServiceImpl -  -- Existing Local Username : admin@local
2020-10-08 01:41:41.227 INFO  [main] c.v.v.v.s.c.DefaultUserInitializer -  -- Initializing default users and roles completed
2020-10-08 01:41:41.229 INFO  [main] c.v.v.l.c.l.MaskingPrintStream -  -- * SYSOUT/SYSERR CAPTURED:  -- Initializing default users and roles completed
2020-10-08 01:41:41.231 INFO  [main] c.v.v.v.s.c.ClientResourceInitializer -  -- Loading config params for setting Client Resource
2020-10-08 01:41:41.390 INFO  [main] c.v.v.v.s.c.ClientResourceInitializerUtil -  -- Truststore initialized with vidm cert.
*
*
*

2020-10-08 01:41:43.473 WARN  [main] c.v.v.l.l.u.PostUpgradeTriggers -  -- Not processing post upgrade triggers yet as reboot is pending.
2020-10-08 01:41:43.473 INFO  [main] c.v.v.l.l.e.PostEveryBootTriggers -  -- **************Invoke triggerPostEveryBoot started**************
2020-10-08 01:41:43.476 INFO  [main] c.v.v.l.l.e.PostEveryBootTriggers -  -- **************Invoke triggerPostEveryBoot completed**************
2020-10-08 01:41:43.476 INFO  [main] c.v.v.l.l.LcmUpdateInitializer -  -- **************Invoke triggerPostUpdate started**************
2020-10-08 01:41:43.497 WARN  [every-boot-thread] c.v.v.l.l.e.PostEveryBootTriggers -  -- Not processing post everyboot triggers yet as reboot is pending.
2020-10-08 01:41:43.493 INFO  [main] c.v.v.l.l.s.SystemServiceImpl -  -- Restarting server detected
2020-10-08 01:41:43.506 INFO  [main] c.v.v.l.l.s.SystemServiceImpl -  -- POST UPDATE  invoked....false
2020-10-08 01:41:43.506 INFO  [main] c.v.v.l.l.s.SystemServiceImpl -  -- Restarting server detected
2020-10-08 01:41:43.507 INFO  [main] c.v.v.l.l.s.SystemServiceImpl -  -- INPROGRESS file not available. Skip Patching instructions
2020-10-08 01:41:43.508 INFO  [main] c.v.v.l.l.LcmUpdateInitializer -  -- **************Invoke triggerPostUpdate completed*************

Populates or Updates the content

2020-10-08 01:42:10.924 INFO  [scheduling-1] c.v.v.l.u.ZipUtility -  -- Successfully populated file paths from the file system 
2020-10-08 01:42:10.926 INFO  [scheduling-1] c.v.v.l.u.ZipUtility -  -- Zipping to dir/var/log/vrlcm/purge/08-10-2020-01-41.zip
*
*
2020-10-08 01:42:21.671 INFO  [http-nio-8080-exec-1] c.v.v.l.c.c.ContentRepositoryController -  -- Creating content operation.
*
2020-10-08 01:42:21.815 INFO  [http-nio-8080-exec-1] c.v.v.l.c.s.ContentDownloadUrlServiceImpl -  -- PATH ::adapterconfiguration.vmfx
*
2020-10-08 01:42:21.849 INFO  [http-nio-8080-exec-5] c.v.v.l.c.c.ContentRepositoryController -  -- Creating content operation.
*
2020-10-08 01:42:21.866 INFO  [http-nio-8080-exec-5] c.v.v.l.c.s.ContentDownloadUrlServiceImpl -  -- PATH ::vravainstall.vmfx
*
2020-10-08 01:42:49.154 INFO  [http-nio-8080-exec-1] c.v.v.l.c.c.ContentRepositoryController -  -- Creating content operation.
2020-10-08 01:42:49.167 INFO  [http-nio-8080-exec-1] c.v.v.l.c.s.ContentDownloadUrlServiceImpl -  -- URL :: /systemflowrepo/system/flow/deletelicenseusage.vmfx
2020-10-08 01:42:49.170 INFO  [http-nio-8080-exec-1] c.v.v.l.c.s.ContentDownloadUrlServiceImpl -  -- URL :: /systemflowrepo/system/flow/deletelicenseusage.vmfx
2020-10-08 01:42:49.170 INFO  [http-nio-8080-exec-1] c.v.v.l.c.s.ContentDownloadUrlServiceImpl -  -- PATH LENGTH :: 5
2020-10-08 01:42:49.171 INFO  [http-nio-8080-exec-1] c.v.v.l.c.s.ContentDownloadUrlServiceImpl -  -- PATH LENGTH TEST PASSED
2020-10-08 01:42:49.172 INFO  [http-nio-8080-exec-1] c.v.v.l.c.s.ContentDownloadUrlServiceImpl -  -- SEARCHINE FOR :: REPO -> systemflowrepo :: NAME -> __ROOT__ :KXKXKXKX PARENT -> f1aea2ef-68a6-4bea-8ebe-8d786b7274b0
2020-10-08 01:42:49.174 INFO  [http-nio-8080-exec-1] c.v.v.l.c.s.ContentDownloadUrlServiceImpl -  -- PATH ::
2020-10-08 01:42:49.175 INFO  [http-nio-8080-exec-1] c.v.v.l.c.s.ContentDownloadUrlServiceImpl -  -- PATH ::systemflowrepo
2020-10-08 01:42:49.175 INFO  [http-nio-8080-exec-1] c.v.v.l.c.s.ContentDownloadUrlServiceImpl -  -- PATH ::system
2020-10-08 01:42:49.176 INFO  [http-nio-8080-exec-1] c.v.v.l.c.s.ContentDownloadUrlServiceImpl -  -- PATH ::flow
2020-10-08 01:42:49.176 INFO  [http-nio-8080-exec-1] c.v.v.l.c.s.ContentDownloadUrlServiceImpl -  -- PATH ::deletelicenseusage.vmfx
2020-10-08 01:42:49.178 INFO  [http-nio-8080-exec-1] c.v.v.l.c.s.ContentDownloadUrlServiceImpl -  -- ADDING NODE - PATH LENGTH :: 5
2020-10-08 01:42:49.178 INFO  [http-nio-8080-exec-1] c.v.v.l.c.s.ContentDownloadUrlServiceImpl -  -- SEARCHINE FOR :: REPO -> systemflowrepo :: PARENT -> bf43d854-9cd7-4012-be70-2d7445a0edab :: NAME -> system
2020-10-08 01:42:49.180 INFO  [http-nio-8080-exec-1] c.v.v.l.c.s.ContentDownloadUrlServiceImpl -  -- SEARCHINE FOR :: REPO -> systemflowrepo :: PARENT -> b05deb67-781f-4273-8344-b5680daa1740 :: NAME -> flow
2020-10-08 01:42:49.182 INFO  [http-nio-8080-exec-1] c.v.v.l.c.s.ContentDownloadUrlServiceImpl -  -- SEARCHINE FOR :: REPO -> systemflowrepo :: PARENT -> b997b4a1-755b-4e31-9917-6aa25e188d25 :: NAME -> deletelicenseusage.vmfx
2020-10-08 01:42:49.184 INFO  [http-nio-8080-exec-1] c.v.v.l.c.s.ContentDownloadUrlServiceImpl -  -- ADDING NODE :: 4

Properties are created or updated

2020-10-08 01:42:50.122 INFO  [http-nio-8080-exec-5] c.v.v.l.d.i.s.InventoryQueryServiceImpl -  -- REQUEST RESOURCE ::
{
  "vmid" : "0c496316-394b-4e83-bd7b-950b9094ccde",
  "transactionId" : null,
  "source" : null,
  "name" : "Lifecycle Operations",
  "reference" : "application",
  "createdOn" : null,
  "children" : [ ],
  "properties" : [ {
    "vmid" : "3356a81a-47e7-45fd-a6a7-7c57ef021992",
    "transactionId" : null,
    "value" : "lcops",
    "source" : null,
    "reference" : "vmid",
    "inputBy" : "USER",
    "secured" : "false"
  }, {
*
*

2020-10-08 01:42:50.929 INFO  [http-nio-8080-exec-5] c.v.v.l.d.i.s.InventoryQueryServiceImpl -  -- PROPERTY TO BE CREATED OR UPDATED ::
{
  "vmid" : "2b399f7a-82c7-4d1a-883f-13c5d11c2bb7",
  "transactionId" : null,
  "value" : "/lcm/marketplace",
  "source" : null,
  "reference" : "appUrl",
  "inputBy" : "USER",
  "secured" : "false"
}
2020-10-08 01:42:50.935 INFO  [http-nio-8080-exec-5] c.v.v.l.d.i.s.InventoryQueryServiceImpl -  -- PROPERTY UPDATED

Updates these ID's , vRLCM codes


2020-10-08 01:42:51.673 INFO  [http-nio-8080-exec-1] c.v.v.l.a.g.s.EngineMessageServiceImpl -  -- Updating the existing : LCMCOMMON80000
2020-10-08 01:42:51.683 INFO  [http-nio-8080-exec-1] c.v.v.l.a.g.s.EngineMessageServiceImpl -  -- Updating the existing : LCMCOMMON80001
2020-10-08 01:42:51.685 INFO  [http-nio-8080-exec-1] c.v.v.l.a.g.s.EngineMessageServiceImpl -  -- Updating the existing : LCMCOMMON80002
2020-10-08 01:42:51.686 INFO  [http-nio-8080-exec-1] c.v.v.l.a.g.s.EngineMessageServiceImpl -  -- Updating the existing : LCMCOMMON80003
*
*

2020-10-08 01:42:52.137 INFO  [http-nio-8080-exec-2] c.v.v.l.a.g.s.EngineMessageServiceImpl -  -- Updating the existing : LCMMYVMWARE60021
2020-10-08 01:42:52.140 INFO  [http-nio-8080-exec-2] c.v.v.l.a.g.s.EngineMessageServiceImpl -  -- Updating the existing : LCMMYVMWARE60022
2020-10-08 01:42:52.149 INFO  [http-nio-8080-exec-2] c.v.v.l.a.g.s.EngineMessageServiceImpl -  -- Updating the existing : LCMMYVMWARE60030
*
*
2020-10-08 01:42:53.598 INFO  [http-nio-8080-exec-10] c.v.v.l.a.g.s.EngineMessageServiceImpl -  -- Updating the existing : LCMVIDM73102
2020-10-08 01:42:53.602 INFO  [http-nio-8080-exec-10] c.v.v.l.a.g.s.EngineMessageServiceImpl -  -- Updating the existing : LCMVIDM73103
2020-10-08 01:42:53.606 INFO  [http-nio-8080-exec-10] c.v.v.l.a.g.s.EngineMessageServiceImpl -  -- Updating the existing : LCMVIDM73104
*
*
2020-10-08 01:42:57.730 INFO  [http-nio-8080-exec-7] c.v.v.l.a.g.s.EngineMessageServiceImpl -  -- Updating the existing : LCMVCF18104
2020-10-08 01:42:57.731 INFO  [http-nio-8080-exec-7] c.v.v.l.a.g.s.EngineMessageServiceImpl -  -- Updating the existing : LCMVCF18105
2020-10-08 01:42:57.733 INFO  [http-nio-8080-exec-7] c.v.v.l.a.g.s.EngineMessageServiceImpl -  -- Updating the existing : LCMVCF18106
*
*
2020-10-08 01:42:57.822 INFO  [http-nio-8080-exec-6] c.v.v.l.c.l.MaskingPrintStream -  -- * SYSOUT/SYSERR CAPTURED:  -- Bulk Imported Role Ids : [8b40cff1-1e4a-4b2b-a059-24de09d3fe37, 33c6d7bd-51f3-41c3-b87c-a63d86fcdf44, 5b821586-7ea0-4093-a4e4-08e47a6ff898, 8ee54b16-2bcc-4f29-bc22-ef2963ae34cc, 155fdcce-f11d-46f8-b2bb-2da307df4044, acd74bb8-8689-4b3c-acbc-abe8a8ce91da, 19a43569-1fd0-4817-94ee-6a24b5a55a23, bd6fcbea-c81a-47a8-9e46-e4ed5aaf28e7]

Once the uploaded content and license associations complete, vRLCM is now rebooted.


2020-10-08 01:43:03.056 INFO  [http-nio-8080-exec-9] c.v.v.l.c.c.ContentRepositoryController -  -- Received upload content request with vmid :: vravainstall
*
*
2020-10-08 01:43:03.329 INFO  [http-nio-8080-exec-8] c.v.v.l.c.c.ContentRepositoryController -  -- Received upload content request with vmid :: vravainitializecluster
*
*
2020-10-08 01:43:03.418 INFO  [http-nio-8080-exec-1] c.v.v.l.c.c.ContentRepositoryController -  -- Received upload content request with vmid :: vravaretrustvidm
*
*

2020-10-08 01:45:37.714 INFO  [scheduling-1] c.v.v.l.a.c.MachineRegistry -  -- GETTING MACHINE FOR THE KEY :: validateandupdateassoc
2020-10-08 01:45:37.715 INFO  [scheduling-1] c.v.v.l.a.c.MachineRegistry -  -- QUERYING CONTENT :: SystemFlowInventory::flows::flow->validateandupdateassoc
2020-10-08 01:45:37.715 INFO  [scheduling-1] c.v.v.l.d.i.u.InventorySchemaQueryUtil -  -- GETTING ROOT NODE FOR :: SystemFlowInventory
2020-10-08 01:45:37.757 INFO  [scheduling-1] c.v.v.l.a.c.MachineRegistry -  -- URL :: /system/flow/validateandupdateassoc.vmfx
2020-10-08 01:45:37.758 INFO  [scheduling-1] c.v.v.l.c.c.ContentDownloadController -  -- INSIDE ContentDownloadControllerImpl
2020-10-08 01:45:37.758 INFO  [scheduling-1] c.v.v.l.c.c.ContentDownloadController -  -- REPO_NAME :: /systemflowrepo
2020-10-08 01:45:37.758 INFO  [scheduling-1] c.v.v.l.c.c.ContentDownloadController -  -- CONTENT_PATH :: /system/flow/validateandupdateassoc.vmfx
2020-10-08 01:45:37.758 INFO  [scheduling-1] c.v.v.l.c.c.ContentDownloadController -  -- URL :: /systemflowrepo/system/flow/validateandupdateassoc.vmfx
2020-10-08 01:45:37.759 INFO  [scheduling-1] c.v.v.l.c.c.ContentDownloadController -  -- Decoded URL :: /systemflowrepo/system/flow/validateandupdateassoc.vmfx
2020-10-08 01:45:37.762 INFO  [scheduling-1] c.v.v.l.c.c.ContentDownloadController -  -- ContentDTO{BaseDTO{vmid='validateandupdateassoc', version=8.1.0.0} -> repoName='systemflowrepo', contentState='PUBLISHED', url='/systemflowrepo/system/flow/validateandupdateassoc.vmfx'}
2020-10-08 01:45:37.764 INFO  [scheduling-1] c.v.v.l.a.c.EventProcessor -  -- Responding for Edge :: OnValidateAndAddLicensesAssocSuccess
2020-10-08 01:45:37.764 INFO  [scheduling-1] c.v.v.l.a.c.EventProcessor -  -- State to find :: com.vmware.vrealize.lcm.plugin.core.licensing.task.ValidateAndUpdateAssocTask
2020-10-08 01:45:37.765 INFO  [scheduling-1] c.v.v.l.a.c.EventProcessor -  -- State to find :: com.vmware.vrealize.lcm.platform.automata.service.task.FinalTask
2020-10-08 01:45:37.769 INFO  [scheduling-1] c.v.v.l.a.c.EventProcessor -  -- Invoking Task :: com.vmware.vrealize.lcm.platform.automata.service.task.FinalTask
2020-10-08 01:45:37.770 INFO  [scheduling-1] c.v.v.l.a.c.EventProcessor -  -- Injecting Bean :: stateMachineInstanceService
2020-10-08 01:45:37.771 INFO  [scheduling-1] c.v.v.l.a.c.EventProcessor -  -- Injecting Bean :: engineRequestService
2020-10-08 01:45:37.771 INFO  [scheduling-1] c.v.v.l.c.t.EventExecutionTelemetryUtil -  -- Start Instrumenting EventMetadata.
2020-10-08 01:45:37.771 INFO  [scheduling-1] c.v.v.l.c.t.EventExecutionTelemetryUtil -  -- Stop Instrumenting EventMetadata.

This is where you see final phase under preupdate.log


2020-10-08 01:45:41 /etc/bootstrap/postupdate.d/25-start-services done, succeeded.

2020-10-08 01:45:41 /etc/bootstrap/postupdate.d/60-update-upgrade-status starting...
2020-10-08 01:45:41 /etc/bootstrap/postupdate.d/60-update-upgrade-status done, succeeded.

2020-10-08 01:45:41 /etc/bootstrap/postupdate.d/70-blackstone-upgrade.sh starting...
2020-10-08 01:45:41 /etc/bootstrap/postupdate.d/70-blackstone-upgrade.sh done, succeeded.

2020-10-08 01:45:41 /etc/bootstrap/postupdate.d/80-cleanup-patch-history starting...
vami upgrade is successful
2020-10-08 01:45:41 /etc/bootstrap/postupdate.d/80-cleanup-patch-history done, succeeded.

2020-10-08 01:45:41 /etc/bootstrap/postupdate.d/81-postgres-autovacuum starting...
auto vacuum setting for request table
ALTER TABLE
2020-10-08 01:45:41 /etc/bootstrap/postupdate.d/81-postgres-autovacuum done, succeeded.
2020-10-08 01:45:41 /etc/bootstrap/postupdate.d/90-enable-fips-mode starting...
2020-10-08 01:45:41 /etc/bootstrap/postupdate.d/90-enable-fips-mode done, succeeded.

2020-10-08 01:45:41 /etc/bootstrap/postupdate.d/99-reboot-va starting...
Creating SUCCESS before rebooting VA...
Rebooting the VA..
2020-10-08 01:45:41 /etc/bootstrap/postupdate.d/99-reboot-va done, succeeded.
2020-10-08 01:45:42 main bootstrap postupdate done

When the appliance is back online approximately 40 seconds later you would see services starting




Executes Post vRLSLCM Upgrade operations



2020-10-08 01:47:55.946 INFO  [main] c.v.v.l.l.u.RequestSubmissionUtil -  -- ++++++++++++++++++ Creating request to Request_Service :::>>> {
 "vmid" : "616e7c05-7c51-489d-9e01-d8096bee64c8",
 "transactionId" : null,
 "requestName" : "postlcmupgrade",
 "requestReason" : "Executing post vRSLCM upgrade operations - Post vRSLCM Upgrade Request",
 "requestType" : "POST_LCM_UPGRADE",
 "requestSource" : null,
 "requestSourceType" : "user",
 "inputMap" : { },
 "outputMap" : { },
 "state" : "CREATED",
 "executionId" : null,
 "executionPath" : null,
 "executionStatus" : null,
 "errorCause" : null,
 "resultSet" : null,
 "isCancelEnabled" : null,
 "lastUpdatedOn" : 1602121675936,
 "createdBy" : null
}

States that all update triggers has been completed



2020-10-08 01:48:11.856 INFO  [main] c.v.v.l.l.u.PostUpgradeTriggers -  -- Processed all upgrade triggers.
2020-10-08 01:48:11.857 INFO  [main] c.v.v.l.l.e.PostEveryBootTriggers -  -- **************Invoke triggerPostEveryBoot started**************
2020-10-08 01:48:11.862 INFO  [main] c.v.v.l.l.e.PostEveryBootTriggers -  -- **************Invoke triggerPostEveryBoot completed**************
2020-10-08 01:48:11.863 INFO  [main] c.v.v.l.l.LcmUpdateInitializer -  -- **************Invoke triggerPostUpdate started**************
2020-10-08 01:48:11.865 INFO  [scheduling-1] c.v.v.l.r.c.RequestProcessor -  -- Number of request to be processed : 3
2020-10-08 01:48:11.869 INFO  [every-boot-thread] c.v.v.l.l.e.PostEveryBootTriggers -  -- Processing 3 everyboot triggers.
2020-10-08 01:48:11.912 INFO  [main] c.v.v.l.l.s.SystemServiceImpl -  -- Restarting server detected
2020-10-08 01:48:11.918 INFO  [main] c.v.v.l.l.s.SystemServiceImpl -  -- POST UPDATE  invoked....false
2020-10-08 01:48:11.919 INFO  [main] c.v.v.l.l.s.SystemServiceImpl -  -- Restarting server detected
2020-10-08 01:48:11.919 INFO  [main] c.v.v.l.l.s.SystemServiceImpl -  -- INPROGRESS file not available. Skip Patching instructions
2020-10-08 01:48:11.925 INFO  [main] c.v.v.l.l.LcmUpdateInitializer -  -- **************Invoke triggerPostUpdate completed**************



Post this your upgrade is now complete

1,579 views0 comments

Comments

Rated 0 out of 5 stars.
No ratings yet

Add a rating
bottom of page