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
Kommentare