Differences

This shows you the differences between two versions of the page.

--- wiki:logs1 [2026/09/04 08:21] – wagner.jer
+++ wiki:logs1 [2026/09/09 11:55] (current) – wagner.jer
@@ Line 1: / Line 1: @@
-<code>root@localhost [ ~ ]# tail -150 /var/log/vmware/applmgmt/applmgmt.log
+root@LX-VV-VC-001 [ /var/log/vmware/applmgmt ]# tail -f /var/log/vmware/applmgmt/applmgmt.log
--09-04T07:51:47 AM UTC [9970]DEBUG:vmware.vherd.transport.authentication:Authentication Server Secret Renewed.
+-09-09T11:51:44 AM UTC [7734]DEBUG:vmware.vherd.transport.xmlrpc:Looking for function get
--09-04T07:56:47 AM UTC [9970]INFO:vmware.vherd.transport.ssh_access_collector:[Unit Test] renewed 50 credits to post event
+-09-09T11:51:44 AM UTC [7734]DEBUG:vmware.vherd.transport.xmlrpc:Method Signature = [['struct', 'string'], '[{"requiredRole": ["requiredRole", false, "str", false]}, {"requiredRole": ["str", "User role."]}, {}, ["requiredRole"], {"suppressCLI": true, "cliAlias": ""}]', 'This interface returns True if the logged in user has\n            the correct required role.', '[{"authorized": ["authorized", false, "bool", false]}, {"authorized": ["bool", "True if the user has the required role"]}, {}, ["authorized"], {"suppressCLI": true, "cliAlias": ""}]']
--09-04T07:56:47 AM UTC [9970]INFO:vmware.vherd.transport.post_sso_events:File /var/log/audit/sso-events/audit_events.log not detected, Exit.
+-09-09T11:52:50 AM UTC [7734]INFO:vmware.appliance.vapi.auth:Authorization request for service_id: com.vmware.appliance.recovery.restore, operation_id: validate
--09-04T07:56:47 AM UTC [9970]INFO:vmware.vherd.transport.post_sso_events:File /var/log/audit/sso-events/operation_events.log not detected, Exit.
+-09-09T11:52:50 AM UTC [7734]DEBUG:vmware.vherd.base.authorization_local:Verify privileges user (root) privilege ['ModifyConfiguration']
--09-04T07:56:47 AM UTC [9970]INFO:vmware.vherd.transport.ssh_access_collector:[Unit Test]Start collecting from sshinfo.log ...
+-09-09T11:52:50 AM UTC [7734]DEBUG:root:Validated user privileges in localstore or SSO
--09-04T07:56:47 AM UTC [9970]DEBUG:vmware.vherd.transport.authentication:Authentication Server Secret Renewed.
+-09-09T11:52:50 AM UTC [7734]DEBUG:vmware.appliance.update.update_state:In State._get using state file /etc/applmgmt/appliance/software_update_state.conf
--09-04T08:01:47 AM UTC [9970]INFO:vmware.vherd.transport.ssh_access_collector:[Unit Test] renewed 50 credits to post event
+-09-09T11:52:50 AM UTC [7734]INFO:plugins.SMBClientStorageIOLib:Running Command: ['/usr/bin/smbclient', '//10.231.192.55/ICT-SOFTWARE/', '-A', '/dev/shm/smbCredentialFilee715ltky', '-t', '140', '-c', 'cd VMWARE/VcenterBackup/vCenter/; ls']
--09-04T08:01:47 AM UTC [9970]INFO:vmware.vherd.transport.post_sso_events:File /var/log/audit/sso-events/audit_events.log not detected, Exit.
+-09-09T11:52:50 AM UTC [7734]ERROR:plugins.SMBClientStorageIOLib:Command failed with error: NT_STATUS_ACCOUNT_RESTRICTION
--09-04T08:01:47 AM UTC [9970]INFO:vmware.vherd.transport.post_sso_events:File /var/log/audit/sso-events/operation_events.log not detected, Exit.
+-09-09T11:52:50 AM UTC [7734]ERROR:plugins.SMBClientStorageIOLib:smb cmd failed. RC: 1, Err: , Cmd: ['/usr/bin/smbclient', '//10.231.192.55/ICT-SOFTWARE/', '-A', '/dev/shm/smbCredentialFilee715ltky', '-t', '140', '-c', 'cd VMWARE/VcenterBackup/vCenter/; ls']
--09-04T08:01:47 AM UTC [9970]INFO:vmware.vherd.transport.ssh_access_collector:[Unit Test]Start collecting from sshinfo.log ...
+-09-09T11:52:50 AM UTC [7734]ERROR:vmware.appliance.vapi.impl:pint.Error('com.vmware.applmgmt.backup.plugin.internal_server_err', 'General system error reported by backup server.', **{'args': ['Plugin error occurred. ErrCode: 5, Args: ()']})
--09-04T08:01:47 AM UTC [9970]DEBUG:vmware.vherd.transport.authentication:Authentication Server Secret Renewed.
+-09-09T11:54:14 AM UTC [7734]INFO:vmware.appliance.vapi.auth:Authorization request for service_id: com.vmware.appliance.recovery.restore, operation_id: validate
--09-04T08:06:47 AM UTC [9970]INFO:vmware.vherd.transport.ssh_access_collector:[Unit Test] renewed 50 credits to post event
+-09-09T11:54:14 AM UTC [7734]DEBUG:vmware.vherd.base.authorization_local:Verify privileges user (root) privilege ['ModifyConfiguration']
--09-04T08:06:47 AM UTC [9970]INFO:vmware.vherd.transport.post_sso_events:File /var/log/audit/sso-events/audit_events.log not detected, Exit.
+-09-09T11:54:14 AM UTC [7734]DEBUG:root:Validated user privileges in localstore or SSO
--09-04T08:06:47 AM UTC [9970]INFO:vmware.vherd.transport.post_sso_events:File /var/log/audit/sso-events/operation_events.log not detected, Exit.
+-09-09T11:54:14 AM UTC [7734]DEBUG:vmware.appliance.update.update_state:In State._get using state file /etc/applmgmt/appliance/software_update_state.conf
--09-04T08:06:47 AM UTC [9970]INFO:vmware.vherd.transport.ssh_access_collector:[Unit Test]Start collecting from sshinfo.log ...
+-09-09T11:54:14 AM UTC [7734]INFO:plugins.SMBClientStorageIOLib:Running Command: ['/usr/bin/smbclient', '//lx-vv-fs-002/ICT-SOFTWARE/', '-A', '/dev/shm/smbCredentialFilez9k4dnoe', '-t', '140', '-c', 'cd VMWARE/VcenterBackup/vCenter/; ls']
--09-04T08:06:47 AM UTC [9970]DEBUG:vmware.vherd.transport.authentication:Authentication Server Secret Renewed.
+-09-09T11:54:14 AM UTC [7734]ERROR:plugins.SMBClientStorageIOLib:Command failed with error: NT_STATUS_ACCOUNT_RESTRICTION
--09-04T08:07:59 AM UTC [9970]INFO:vmware.appliance.vapi.auth:Authorization request for service_id: com.vmware.cis.session, operation_id: create
+-09-09T11:54:14 AM UTC [7734]ERROR:plugins.SMBClientStorageIOLib:smb cmd failed. RC: 1, Err: , Cmd: ['/usr/bin/smbclient', '//lx-vv-fs-002/ICT-SOFTWARE/', '-A', '/dev/shm/smbCredentialFilez9k4dnoe', '-t', '140', '-c', 'cd VMWARE/VcenterBackup/vCenter/; ls']
--09-04T08:07:59 AM UTC [9970]DEBUG:vmware.appliance.update.update_state:In State._get using state file /etc/applmgmt/appliance/software_update_state.conf
+-09-09T11:54:14 AM UTC [7734]ERROR:vmware.appliance.vapi.impl:pint.Error('com.vmware.applmgmt.backup.plugin.internal_server_err', 'General system error reported by backup server.', **{'args': ['Plugin error occurred. ErrCode: 5, Args: ()']})
--09-04T08:07:59 AM UTC [9970]INFO:vmware.appliance.vapi.auth:Reset pam tally for root
--09-04T08:07:59 AM UTC [9970]DEBUG:vmware.appliance.vapi.auth:stdout: b''
--09-04T08:07:59 AM UTC [9970]INFO:vmware.appliance.vapi.auth:Authorization request for service_id: com.vmware.vcenter.deployment.upgrade, operation_id: check
--09-04T08:07:59 AM UTC [9970]DEBUG:vmware.vherd.base.authorization_local:Verify privileges user (root) privilege ['ModifyConfiguration']
--09-04T08:07:59 AM UTC [9970]DEBUG:root:Validated user privileges in localstore or SSO
--09-04T08:07:59 AM UTC [9970]DEBUG:vmware.appliance.update.update_state:In State._get using state file /etc/applmgmt/appliance/software_update_state.conf
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.deploy_status:Deploy state: {'state': 'initialized', 'time': '2026-09-04T06:46:47.459Z'}
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.deployment_impl:Appliance state retrieved: INITIALIZED.
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.global_mutex:Using '/etc/vmware/deploy/lifecycle.global.mutex' as the global mutex file.
--09-04T08:07:59 AM UTC [9970]DEBUG:cis.filelock:Acquiring lock /etc/vmware/deploy/lifecycle.global.mutex.upgradeLock
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.global_mutex:Global mutex successfully acquired.
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.deploy_status:PhaseStatusWriter initialized
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.common_utils:Returning deployment type as 'VCSA_EMBEDDED'
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.spec_validators:Using default 'False' value for auto_answer mode.
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.spec_validators:Using default 'False' value for skip_products_prechecks.
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.spec_validators:Checking given deployment type structure is valid for this deployment.
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.spec_validators:Validating source_appliance: 10.231.192.71
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.network_utils:Testing connection to 10.231.192.71:443.
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.network_utils:Connection test to '10.231.192.71:443' was successful.
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.spec_validators:Checking source_appliance SSL certificate: 10.231.192.71
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.spec_validators:No verification is performed.
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.network_utils:Address 'lx-vv-vc-001.d7029d001.bradesco.lu' is not an IPv4 address.
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.network_utils:Address 'lx-vv-vc-001.d7029d001.bradesco.lu' is not an IPv6 address.
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.network_utils:Ignoring non-IP/DNS subjectAltName extension: 'email:email@acme.com'
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.network_utils:Returning IPs {'10.231.192.71'} and FQDNs {'lx-vv-vc-001.d7029d001.bradesco.lu'}.
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.network_utils:Passed IPv4 address '10.231.192.71' is compatible with the server certificate.
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.spec_validators:Checking credentials for 10.231.192.71 via pyvmomi ...
--09-04T08:07:59 AM UTC [9970]WARNING:vcenter.pyvmomi_utils:Not validating server certificate.
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.spec_validators:Successfully validated credentials for: 10.231.192.71
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.spec_validators:Checking source_appliance root_password: 10.231.192.71
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.spec_validators:SSH verification will not be performed.
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.spec_validators:Validating connection.
--09-04T08:07:59 AM UTC [9970]DEBUG:paramiko.transport:starting thread (client mode): 0xe40bef80
--09-04T08:07:59 AM UTC [9970]DEBUG:paramiko.transport:Local version/idstring: SSH-2.0-paramiko_2.10.3
--09-04T08:07:59 AM UTC [9970]DEBUG:paramiko.transport:Remote version/idstring: SSH-2.0-OpenSSH_7.8
--09-04T08:07:59 AM UTC [9970]INFO:paramiko.transport:Connected (version 2.0, client OpenSSH_7.8)
--09-04T08:07:59 AM UTC [9970]DEBUG:paramiko.transport:=== Key exchange possibilities ===
--09-04T08:07:59 AM UTC [9970]DEBUG:paramiko.transport:kex algos: ecdh-sha2-nistp256, ecdh-sha2-nistp384, ecdh-sha2-nistp521, diffie-hellman-group-exchange-sha256, diffie-hellman-group16-sha512, diffie-hellman-group18-sha512, diffie-hellman-group14-sha256
--09-04T08:07:59 AM UTC [9970]DEBUG:paramiko.transport:server key: rsa-sha2-512, rsa-sha2-256, ecdsa-sha2-nistp256, ssh-ed25519
--09-04T08:07:59 AM UTC [9970]DEBUG:paramiko.transport:client encrypt: aes128-ctr, aes192-ctr, aes256-ctr, aes128-gcm@openssh.com, aes256-gcm@openssh.com
--09-04T08:07:59 AM UTC [9970]DEBUG:paramiko.transport:server encrypt: aes128-ctr, aes192-ctr, aes256-ctr, aes128-gcm@openssh.com, aes256-gcm@openssh.com
--09-04T08:07:59 AM UTC [9970]DEBUG:paramiko.transport:client mac: hmac-sha2-512-etm@openssh.com, hmac-sha2-256-etm@openssh.com, hmac-sha2-512, hmac-sha2-256
--09-04T08:07:59 AM UTC [9970]DEBUG:paramiko.transport:server mac: hmac-sha2-512-etm@openssh.com, hmac-sha2-256-etm@openssh.com, hmac-sha2-512, hmac-sha2-256
--09-04T08:07:59 AM UTC [9970]DEBUG:paramiko.transport:client compress: none
--09-04T08:07:59 AM UTC [9970]DEBUG:paramiko.transport:server compress: none
--09-04T08:07:59 AM UTC [9970]DEBUG:paramiko.transport:client lang: <none>
--09-04T08:07:59 AM UTC [9970]DEBUG:paramiko.transport:server lang: <none>
--09-04T08:07:59 AM UTC [9970]DEBUG:paramiko.transport:kex follows: False
--09-04T08:07:59 AM UTC [9970]DEBUG:paramiko.transport:=== Key exchange agreements ===
--09-04T08:07:59 AM UTC [9970]DEBUG:paramiko.transport:Kex: ecdh-sha2-nistp256
--09-04T08:07:59 AM UTC [9970]DEBUG:paramiko.transport:HostKey: ssh-ed25519
--09-04T08:07:59 AM UTC [9970]DEBUG:paramiko.transport:Cipher: aes128-ctr
--09-04T08:07:59 AM UTC [9970]DEBUG:paramiko.transport:MAC: hmac-sha2-256
--09-04T08:07:59 AM UTC [9970]DEBUG:paramiko.transport:Compression: none
--09-04T08:07:59 AM UTC [9970]DEBUG:paramiko.transport:=== End of kex handshake ===
--09-04T08:07:59 AM UTC [9970]DEBUG:paramiko.transport:kex engine KexNistp256 specified hash_algo <built-in function openssl_sha256>
--09-04T08:07:59 AM UTC [9970]DEBUG:paramiko.transport:Switch to new keys ...
--09-04T08:07:59 AM UTC [9970]DEBUG:paramiko.transport:Got EXT_INFO: {'server-sig-algs': b'ssh-ed25519,ssh-rsa,rsa-sha2-256,rsa-sha2-512,ssh-dss,ecdsa-sha2-nistp256,ecdsa-sha2-nistp384,ecdsa-sha2-nistp521'}
--09-04T08:07:59 AM UTC [9970]WARNING:vcenter.network_utils:Not verifying SSH key for SSH connection to '10.231.192.71'.
--09-04T08:07:59 AM UTC [9970]DEBUG:paramiko.transport:userauth is OK
--09-04T08:07:59 AM UTC [9970]INFO:paramiko.transport:Auth banner: b'\nVMware vCenter Server 7.0.3.02400\n\nType: vCenter Server with an embedded Platform Services Controller\n\n'
--09-04T08:07:59 AM UTC [9970]INFO:paramiko.transport:Authentication (password) successful!
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.spec_validators:Successfully validated SSH root credential.
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.spec_validators:Validating source_location: 10.231.202.54
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.network_utils:Testing connection to 10.231.202.54:443.
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.network_utils:Connection test to '10.231.202.54:443' was successful.
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.spec_validators:Checking source_location SSL certificate: 10.231.202.54
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.spec_validators:No verification is performed.
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.network_utils:Address 'lx-fv-hv-001b.d7029d001.bradesco.lu' is not an IPv4 address.
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.network_utils:Address 'lx-fv-hv-001b.d7029d001.bradesco.lu' is not an IPv6 address.
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.network_utils:Returning IPs set() and FQDNs {'lx-fv-hv-001b.d7029d001.bradesco.lu'}.
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.network_utils:Address 'lx-fv-hv-001b.d7029d001.bradesco.lu' is not an IPv4 address.
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.network_utils:Address 'lx-fv-hv-001b.d7029d001.bradesco.lu' is not an IPv6 address.
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.network_utils:Address 'lx-fv-hv-001b.d7029d001.bradesco.lu' is not an IPv4 address.
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.network_utils:Address 'lx-fv-hv-001b.d7029d001.bradesco.lu' is not an IPv6 address.
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.network_utils:Address 'lx-fv-hv-001b.d7029d001.bradesco.lu' matches fqdn_pattern 'lx-fv-hv-001b.d7029d001.bradesco.lu'.
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.network_utils:Fqdn from reverse lookup 'lx-fv-hv-001b.d7029d001.bradesco.lu' is compatible with the server certificate.
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.spec_validators:Checking credentials for 10.231.202.54 via pyvmomi ...
--09-04T08:07:59 AM UTC [9970]WARNING:vcenter.pyvmomi_utils:Not validating server certificate.
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.spec_validators:Successfully validated credentials for: 10.231.202.54
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.spec_parser:Initializing upgrade spec parser.
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.spec_validators:Setting install parameters for upgrade.
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.spec_parser:'source_appliance' is dictionary
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.fileutil:Setting install parameter: upgrade.source.vpxd.ip
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.fileutil:Setting install parameter: upgrade.source.vpxd.port
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.spec_parser:Spec key ssl_verify not in default spec, skipping
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.fileutil:Setting install parameter: upgrade.source.vpxd.user
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.fileutil:Setting install parameter: vmdir.username
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.fileutil:Setting install parameter: upgrade.source.vpxd.password
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.fileutil:Setting install parameter: vmdir.password
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.fileutil:Setting install parameter: upgrade.source.guest.password
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.spec_parser:Spec key ssh_verify not in default spec, skipping
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.spec_parser:'source_location' is dictionary
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.network_utils:Address '10.231.202.54' is not an IPv6 address.
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.spec_parser:setting host_address to //10.231.202.54:443
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.fileutil:Setting install parameter: upgrade.source.guestops.host.addr
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.fileutil:Setting install parameter: upgrade.source.guestops.host.port
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.spec_parser:Spec key ssl_verify not in default spec, skipping
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.fileutil:Setting install parameter: upgrade.source.guestops.host.user
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.fileutil:Setting install parameter: upgrade.source.guestops.host.password
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.spec_parser:'vcsa_embedded' is dictionary
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.fileutil:Setting install parameter: ceip_enabled
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.fileutil:Setting install parameter: vmdir.first-instance
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.fileutil:Setting install parameter: upgrade.source.export.directory
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.fileutil:Setting install parameter: silentinstall
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.fileutil:Setting install parameter: upgrade.skip.products.interop.precheck
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.spec_parser:No value specified for history. Setting it to the default value 'core'
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.fileutil:Setting install parameter: upgrade.user.options
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.fileutil:Setting install parameter: upgrade.source.guest.user
--09-04T08:07:59 AM UTC [9970]INFO:vcenter.spec_validators:Invoking script: /usr/lib/applmgmt/vcenter/bin/invoke_precheck.sh
--09-04T08:07:59 AM UTC [9970]DEBUG:paramiko.transport:EOF in transport thread
--09-04T08:11:47 AM UTC [9970]INFO:vmware.vherd.transport.ssh_access_collector:[Unit Test] renewed 50 credits to post event
--09-04T08:11:47 AM UTC [9970]INFO:vmware.vherd.transport.post_sso_events:File /var/log/audit/sso-events/audit_events.log not detected, Exit.
--09-04T08:11:47 AM UTC [9970]INFO:vmware.vherd.transport.ssh_access_collector:[Unit Test]Start collecting from sshinfo.log ...
--09-04T08:11:47 AM UTC [9970]INFO:vmware.vherd.transport.post_sso_events:File /var/log/audit/sso-events/operation_events.log not detected, Exit.
--09-04T08:11:47 AM UTC [9970]DEBUG:vmware.vherd.transport.authentication:Authentication Server Secret Renewed.
--09-04T08:13:59 AM UTC [9970]INFO:vmware.appliance.vapi.auth:Authorization request for service_id: com.vmware.vcenter.deployment.upgrade, operation_id: check
--09-04T08:13:59 AM UTC [9970]DEBUG:vmware.vherd.base.authorization_local:Verify privileges user (root) privilege ['ModifyConfiguration']
--09-04T08:13:59 AM UTC [9970]DEBUG:root:Validated user privileges in localstore or SSO
--09-04T08:13:59 AM UTC [9970]DEBUG:vmware.appliance.update.update_state:In State._get using state file /etc/applmgmt/appliance/software_update_state.conf
--09-04T08:13:59 AM UTC [9970]INFO:vcenter.deploy_status:Deploy state: {'state': 'initialized', 'time': '2026-09-04T06:46:47.459Z'}
--09-04T08:13:59 AM UTC [9970]INFO:vcenter.deployment_impl:Appliance state retrieved: INITIALIZED.
--09-04T08:13:59 AM UTC [9970]INFO:vcenter.global_mutex:Using '/etc/vmware/deploy/lifecycle.global.mutex' as the global mutex file.
--09-04T08:13:59 AM UTC [9970]DEBUG:cis.filelock:Acquiring lock /etc/vmware/deploy/lifecycle.global.mutex.upgradeLock
--09-04T08:13:59 AM UTC [9970]WARNING:vcenter.global_mutex:Failed to acquire global mutex.
--09-04T08:13:59 AM UTC [9970]INFO:vcenter.fileutil:read_json_file: [Errno 2] No such file or directory: '/etc/vmware/deploy/operation.json'
--09-04T08:13:59 AM UTC [9970]ERROR:vcenter.deployment_impl:This operation is not allowed in the current state as operation 'None' is already in progress.
--09-04T08:16:47 AM UTC [9970]INFO:vmware.vherd.transport.ssh_access_collector:[Unit Test] renewed 50 credits to post event
--09-04T08:16:47 AM UTC [9970]INFO:vmware.vherd.transport.post_sso_events:File /var/log/audit/sso-events/audit_events.log not detected, Exit.
--09-04T08:16:47 AM UTC [9970]INFO:vmware.vherd.transport.post_sso_events:File /var/log/audit/sso-events/operation_events.log not detected, Exit.
--09-04T08:16:47 AM UTC [9970]INFO:vmware.vherd.transport.ssh_access_collector:[Unit Test]Start collecting from sshinfo.log ...
--09-04T08:16:47 AM UTC [9970]DEBUG:vmware.vherd.transport.authentication:Authentication Server Secret Renewed.
-root@localhost [ ~ ]# journalctl -u applmgmt -n 100 --no-logger
-journalctl: unrecognized option '--no-logger'
-root@localhost [ ~ ]# journalctl -u applmgmt -n 100 --no-pager
--- Journal begins at Fri 2026-09-04 06:42:22 UTC, ends at Fri 2026-09-04 08:19:02 UTC. --
-Sep 04 06:42:39 localhost systemd[1]: Started Appliance Management Service..
-Sep 04 06:42:39 localhost get-build-number[1798]: root Cannot retrieve build number from /etc/applmgmt/appliance/update.conf: 'build'
-Sep 04 06:42:41 localhost applmgmt-systemd.launcher[1797]: 2026-09-04 06:42:41,575 - 1797 - INFO - vcsa_network_base - 80 - StreamHandler added
-Sep 04 06:42:41 localhost applmgmt-systemd.launcher[1797]: 2026-09-04 06:42:41,575 - 1797 - INFO - vcsa_network_base - 66 - NetworkUtil; Doesn't exist creating one.
-Sep 04 06:43:19 localhost systemd[1]: Stopping Appliance Management Service....
-Sep 04 06:43:20 localhost systemd[1]: applmgmt.service: Succeeded.
-Sep 04 06:43:20 localhost systemd[1]: Stopped Appliance Management Service..
-Sep 04 06:43:20 localhost systemd[1]: applmgmt.service: Consumed 9.686s CPU time.
-Sep 04 06:43:20 localhost systemd[1]: Started Appliance Management Service..
-Sep 04 06:43:21 localhost applmgmt-systemd.launcher[3907]: 2026-09-04 06:43:21,496 - 3907 - INFO - vcsa_network_base - 80 - StreamHandler added
-Sep 04 06:43:21 localhost applmgmt-systemd.launcher[3907]: 2026-09-04 06:43:21,496 - 3907 - INFO - vcsa_network_base - 66 - NetworkUtil; Doesn't exist creating one.
-Sep 04 06:43:49 localhost systemd[1]: Stopping Appliance Management Service....
-Sep 04 06:43:50 localhost systemd[1]: applmgmt.service: Succeeded.
-Sep 04 06:43:50 localhost systemd[1]: Stopped Appliance Management Service..
-Sep 04 06:43:50 localhost systemd[1]: applmgmt.service: Consumed 8.572s CPU time.
-Sep 04 06:43:50 localhost systemd[1]: Started Appliance Management Service..
-Sep 04 06:43:51 localhost applmgmt-systemd.launcher[4057]: 2026-09-04 06:43:51,817 - 4057 - INFO - vcsa_network_base - 80 - StreamHandler added
-Sep 04 06:43:51 localhost applmgmt-systemd.launcher[4057]: 2026-09-04 06:43:51,817 - 4057 - INFO - vcsa_network_base - 66 - NetworkUtil; Doesn't exist creating one.
-Sep 04 06:46:45 localhost systemd[1]: Stopping Appliance Management Service....
-Sep 04 06:46:46 localhost systemd[1]: applmgmt.service: Succeeded.
-Sep 04 06:46:46 localhost systemd[1]: Stopped Appliance Management Service..
-Sep 04 06:46:46 localhost systemd[1]: applmgmt.service: Consumed 8.438s CPU time.
-Sep 04 06:46:46 localhost systemd[1]: Started Appliance Management Service..
-Sep 04 06:46:47 localhost applmgmt-systemd.launcher[9970]: Error Code : 1021  UNKNOWN ERROR
-Sep 04 06:46:47 localhost applmgmt-systemd.launcher[9970]: 2026-09-04 06:46:47,216 - 9970 - INFO - vcsa_network_base - 80 - StreamHandler added
-Sep 04 06:46:47 localhost applmgmt-systemd.launcher[9970]: 2026-09-04 06:46:47,216 - 9970 - INFO - vcsa_network_base - 66 - NetworkUtil; Doesn't exist creating one.
-Sep 04 07:04:25 localhost applmgmt-systemd.launcher[9970]: Error Code : 1021  UNKNOWN ERROR
-Sep 04 07:04:25 localhost applmgmt-systemd.launcher[9970]: Error Code : 1021  UNKNOWN ERROR
-Sep 04 07:05:48 localhost applmgmt-systemd.launcher[9970]: 2026-09-04T07:05:48.671Z  Setting upgrade.source.vpxd.ip install-parameter to: 10.231.192.71
-Sep 04 07:05:48 localhost applmgmt-systemd.launcher[9970]: 2026-09-04T07:05:48.672Z  Setting upgrade.source.vpxd.port install-parameter to: 443
-Sep 04 07:05:48 localhost applmgmt-systemd.launcher[9970]: 2026-09-04T07:05:48.672Z  Setting upgrade.source.vpxd.user install-parameter to: administrator@vsphere.local
-Sep 04 07:05:48 localhost applmgmt-systemd.launcher[9970]: 2026-09-04T07:05:48.672Z  Setting vmdir.username install-parameter to: administrator@vsphere.local
-Sep 04 07:05:48 localhost applmgmt-systemd.launcher[9970]: 2026-09-04T07:05:48.672Z  Setting upgrade.source.vpxd.password install-parameter to: --CENSORED--
-Sep 04 07:05:48 localhost applmgmt-systemd.launcher[9970]: 2026-09-04T07:05:48.672Z  Setting vmdir.password install-parameter to: --CENSORED--
-Sep 04 07:05:48 localhost applmgmt-systemd.launcher[9970]: 2026-09-04T07:05:48.672Z  Setting upgrade.source.guest.password install-parameter to: --CENSORED--
-Sep 04 07:05:48 localhost applmgmt-systemd.launcher[9970]: 2026-09-04T07:05:48.673Z  Setting upgrade.source.guestops.host.addr install-parameter to: //10.231.202.54:443
-Sep 04 07:05:48 localhost applmgmt-systemd.launcher[9970]: 2026-09-04T07:05:48.673Z  Setting upgrade.source.guestops.host.port install-parameter to: 443
-Sep 04 07:05:48 localhost applmgmt-systemd.launcher[9970]: 2026-09-04T07:05:48.673Z  Setting upgrade.source.guestops.host.user install-parameter to: root
-Sep 04 07:05:48 localhost applmgmt-systemd.launcher[9970]: 2026-09-04T07:05:48.673Z  Setting upgrade.source.guestops.host.password install-parameter to: --CENSORED--
-Sep 04 07:05:48 localhost applmgmt-systemd.launcher[9970]: 2026-09-04T07:05:48.673Z  Setting ceip_enabled install-parameter to: False
-Sep 04 07:05:48 localhost applmgmt-systemd.launcher[9970]: 2026-09-04T07:05:48.673Z  Setting vmdir.first-instance install-parameter to: True
-Sep 04 07:05:48 localhost applmgmt-systemd.launcher[9970]: 2026-09-04T07:05:48.673Z  Setting upgrade.source.export.directory install-parameter to:
-Sep 04 07:05:48 localhost applmgmt-systemd.launcher[9970]: 2026-09-04T07:05:48.673Z  Setting silentinstall install-parameter to: False
-Sep 04 07:05:48 localhost applmgmt-systemd.launcher[9970]: 2026-09-04T07:05:48.680Z  Setting upgrade.skip.products.interop.precheck install-parameter to: False
-Sep 04 07:05:48 localhost applmgmt-systemd.launcher[9970]: 2026-09-04T07:05:48.681Z  Setting upgrade.user.options install-parameter to: {"vcdb.migrateSet": "core"}
-Sep 04 07:05:48 localhost applmgmt-systemd.launcher[9970]: 2026-09-04T07:05:48.681Z  Setting upgrade.source.guest.user install-parameter to: root
-Sep 04 08:07:59 localhost applmgmt-systemd.launcher[9970]: 2026-09-04T08:07:59.479Z  Setting upgrade.source.vpxd.ip install-parameter to: 10.231.192.71
-Sep 04 08:07:59 localhost applmgmt-systemd.launcher[9970]: 2026-09-04T08:07:59.479Z  Setting upgrade.source.vpxd.port install-parameter to: 443
-Sep 04 08:07:59 localhost applmgmt-systemd.launcher[9970]: 2026-09-04T08:07:59.479Z  Setting upgrade.source.vpxd.user install-parameter to: administrator@vsphere.local
-Sep 04 08:07:59 localhost applmgmt-systemd.launcher[9970]: 2026-09-04T08:07:59.479Z  Setting vmdir.username install-parameter to: administrator@vsphere.local
-Sep 04 08:07:59 localhost applmgmt-systemd.launcher[9970]: 2026-09-04T08:07:59.480Z  Setting upgrade.source.vpxd.password install-parameter to: --CENSORED--
-Sep 04 08:07:59 localhost applmgmt-systemd.launcher[9970]: 2026-09-04T08:07:59.480Z  Setting vmdir.password install-parameter to: --CENSORED--
-Sep 04 08:07:59 localhost applmgmt-systemd.launcher[9970]: 2026-09-04T08:07:59.480Z  Setting upgrade.source.guest.password install-parameter to: --CENSORED--
-Sep 04 08:07:59 localhost applmgmt-systemd.launcher[9970]: 2026-09-04T08:07:59.481Z  Setting upgrade.source.guestops.host.addr install-parameter to: //10.231.202.54:443
-Sep 04 08:07:59 localhost applmgmt-systemd.launcher[9970]: 2026-09-04T08:07:59.481Z  Setting upgrade.source.guestops.host.port install-parameter to: 443
-Sep 04 08:07:59 localhost applmgmt-systemd.launcher[9970]: 2026-09-04T08:07:59.482Z  Setting upgrade.source.guestops.host.user install-parameter to: root
-Sep 04 08:07:59 localhost applmgmt-systemd.launcher[9970]: 2026-09-04T08:07:59.482Z  Setting upgrade.source.guestops.host.password install-parameter to: --CENSORED--
-Sep 04 08:07:59 localhost applmgmt-systemd.launcher[9970]: 2026-09-04T08:07:59.482Z  Setting ceip_enabled install-parameter to: False
-Sep 04 08:07:59 localhost applmgmt-systemd.launcher[9970]: 2026-09-04T08:07:59.482Z  Setting vmdir.first-instance install-parameter to: True
-Sep 04 08:07:59 localhost applmgmt-systemd.launcher[9970]: 2026-09-04T08:07:59.482Z  Setting upgrade.source.export.directory install-parameter to:
-Sep 04 08:07:59 localhost applmgmt-systemd.launcher[9970]: 2026-09-04T08:07:59.483Z  Setting silentinstall install-parameter to: False
-Sep 04 08:07:59 localhost applmgmt-systemd.launcher[9970]: 2026-09-04T08:07:59.483Z  Setting upgrade.skip.products.interop.precheck install-parameter to: False
-Sep 04 08:07:59 localhost applmgmt-systemd.launcher[9970]: 2026-09-04T08:07:59.483Z  Setting upgrade.user.options install-parameter to: {"vcdb.migrateSet": "core"}
-Sep 04 08:07:59 localhost applmgmt-systemd.launcher[9970]: 2026-09-04T08:07:59.483Z  Setting upgrade.source.guest.user install-parameter to: root
-Sep 04 08:16:58 localhost systemd[1]: Stopping Appliance Management Service....
-Sep 04 08:17:18 localhost systemd[1]: applmgmt.service: State 'stop-sigterm' timed out. Killing.
-Sep 04 08:17:18 localhost systemd[1]: applmgmt.service: Killing process 9970 (python) with signal SIGKILL.
-Sep 04 08:17:18 localhost systemd[1]: applmgmt.service: Main process exited, code=killed, status=9/KILL
-Sep 04 08:17:18 localhost systemd[1]: applmgmt.service: Failed with result 'timeout'.
-Sep 04 08:17:18 localhost systemd[1]: applmgmt.service: Unit process 18301 (invoke_precheck) remains running after unit stopped.
-Sep 04 08:17:18 localhost systemd[1]: applmgmt.service: Unit process 18345 (bash) remains running after unit stopped.
-Sep 04 08:17:18 localhost systemd[1]: applmgmt.service: Unit process 18413 (python3) remains running after unit stopped.
-Sep 04 08:17:18 localhost systemd[1]: applmgmt.service: Unit process 18414 (tee) remains running after unit stopped.
-Sep 04 08:17:18 localhost systemd[1]: Stopped Appliance Management Service..
-Sep 04 08:17:18 localhost systemd[1]: applmgmt.service: Consumed 45.096s CPU time.
-root@localhost [ ~ ]# service-control --status applmgmt
-Stopped:
- applmgmt
-root@localhost [ ~ ]# ls -la /etc/applmgmt/appliance/software_update_state.conf
--rw-r--r-- 1 root root 29 Sep  4 06:42 /etc/applmgmt/appliance/software_update_state.conf
-root@localhost [ ~ ]# cat /etc/applmgmt/appliance/software_update_state.conf
-{
-    "state": "UP_TO_DATE"
-}root@localhost [ ~ ]# python3 -c "import json; print(json.load(open('/etc/applmgmt/appliance/software_update_state.conf')))"
-{'state': 'UP_TO_DATE'}
-root@localhost [ ~ ]#
-</code>