top of page

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,496 views0 comments
bottom of page