Dear community,
I need a little bit of help for the following issue.
My Update Manager Service is not starting, and I have no idea how can I resolve this.
I give you some insights:
Trying to start from console:
# service-control --start vmware-updatemgr
root@s-vcsa01 [ ~ ]# tail /var/log/vmware/vmware-updatemgr/vum-server/vmware-vum-server-log4cpp.log
[2019-10-08 11:29:42:961 'managedJetty' 140573911529216 INFO] [managedJetty, 449] Jetty: 2019-10-08 11:29:42.961:INFO:oejs.AbstractConnector:Thre ad-0: Stopped ServerConnector@70dbaee1{SSL,[ssl, http/1.1]}{0.0.0.0:9087}
[2019-10-08 11:29:43:194 'managedJetty' 140573909931776 INFO] [managedJetty, 449] Jetty: 2019-10-08 11:29:43.186:INFO:oejsh.ContextHandler:Thread -0: Stopped o.e.j.w.WebAppContext@1f57539{FileUpload Service,/vum-fileupload,null,UNAVAILABLE}{/vum-fileupload.war}
[2019-10-08 11:29:43:206 'managedJetty' 140573910464256 INFO] [managedJetty, 449] Jetty: 2019-10-08 11:29:43.200:INFO:oejsh.ContextHandler:Thread -0: Stopped o.e.j.s.h.ContextHandler@527740a2{VUM Static Downloads,/vci/downloads,file:///usr/lib/vmware-updatemgr/bin/docroot/vci/downloads/,UNAV AILABLE}
[2019-10-08 11:29:43:208 'managedJetty' 140574548424448 INFO] [managedJetty, 449] Jetty: 2019-10-08 11:29:43.208:INFO:oejsh.ContextHandler:Thread -0: Stopped o.e.j.s.h.ContextHandler@6d7b4f4c{VUM Patch Store,/vum/repository,file:///storage/updatemgr/patch-store/,UNAVAILABLE}
[2019-10-08 11:29:43:228 'managedJetty' 140573910730496 INFO] [managedJetty, 449] Jetty: 2019-10-08 11:29:43.219:INFO:oejs.session:Thread-0: node 0 Stopped scavenging
[2019-10-08 11:29:43:467 'managedJetty' 140573910730496 INFO] [managedJetty, 466] Jetty terminated.
[2019-10-08 11:29:43:486 'managedJetty' 140573911795456 INFO] [managedJetty, 491] Jetty exited with status 0.
[2019-10-08 11:29:44:496 'VcIntegrityPlugin' 140574701828160 INFO] [plugin, 296] VcIntegrityPlugin WaitStop
[2019-10-08 11:29:44:496 'VcIntegrity' 140574701828160 INFO] [vcIntegrity, 1005] Reset enter
[2019-10-08 11:29:44:496 'VcIntegrity' 140574701828160 INFO] [vcIntegrity, 1041] Reset leave
2nd try:
root@s-vcsa01 [ ~ ]# service-control --start vmware-updatemgr
Operation not cancellable. Please wait for it to finish...
Performing start operation on service updatemgr...
Error executing start on service updatemgr. Details {
"componentKey": null,
"problemId": null,
"resolution": null,
"detail": [
{
"id": "install.ciscommon.service.failstart",
"localized": "An error occurred while starting service 'updatemgr'",
"translatable": "An error occurred while starting service '%(0)s'",
"args": [
"updatemgr"
]
}
]
}
Service-control failed. Error: {
"componentKey": null,
"problemId": null,
"resolution": null,
"detail": [
{
"id": "install.ciscommon.service.failstart",
"localized": "An error occurred while starting service 'updatemgr'",
"translatable": "An error occurred while starting service '%(0)s'",
"args": [
"updatemgr"
]
}
]
}
Logfiles:
root@s-vcsa01 [ ~ ]# tail /var/log/vmware/vmware-updatemgr/updatemgr-utility.log
"translatable": "An error occurred while starting service '%(0)s'",
"args": [
"updatemgr"
],
"id": "install.ciscommon.service.failstart"
}
]
}
[2019-10-08 21:32:33,012 ERROR] Unable to start VUM service
[2019-10-10 10:13:00,324 ERROR] Exception while executing updatemgr-utility. err: 'Namespace' object has no attribute 'func'
From Appliance Bash:
Command> com.vmware.updatemgr-util
Exception while executing updatemgr-utility. err: 'Namespace' object has no attribute 'func'
All ideas are really welcome, thanks.
Regards,
Daniel
OK...finaly a new VCSA is installed...all steps with no success..
/close
Hello,
really no one has any idea?
Can you please try with steps in https://vwannabe.com/2018/02/21/how-to-re-register-the-embedded-vmware-update-manager-vum-to-its-vce...
Hello Satys,
thanks for your hint.
But, no success with this:
root@server [ ~ ]# /lib/vmware-updatemgr/bin/vmware-vciInstallUtils -C /lib/vmware-updatemgr/bin/ -L /var/log/vmware/vmware-updatemgr/ -I /lib/vmware-updatemgr/bin/ -v server -p 80 -U administrator -P Password -S /lib/vmware-updatemgr/bin/extension.xml -O extupdate
2019-10-18T01:06:52.852+02:00 info -[49254] [Originator@6876 sub=Default] Glibc malloc guards disabled.
2019-10-18T01:06:52.852+02:00 info -[49254] [Originator@6876 sub=Default] Initialized SystemFactory
2019-10-18T01:06:52.852+02:00 info vmware-vci-vciInstallUtils[49254] [Originator@6876 sub=Default] Logging uses fast path: false
2019-10-18T01:06:52.852+02:00 info vmware-vci-vciInstallUtils[49254] [Originator@6876 sub=Default] The bora/lib logs WILL be handled by VmaCore
2019-10-18T01:06:52.852+02:00 info vmware-vci-vciInstallUtils[49254] [Originator@6876 sub=Default] Initialized channel manager
2019-10-18T01:06:52.852+02:00 info vmware-vci-vciInstallUtils[49254] [Originator@6876 sub=Default] Current working directory: /root
2019-10-18T01:06:52.852+02:00 info vmware-vci-vciInstallUtils[49254] [Originator@6876 sub=ThreadPool] Catch work item exceptions disabled.
2019-10-18T01:06:52.852+02:00 info vmware-vci-vciInstallUtils[49254] [Originator@6876 sub=FairScheduler] Priority level 4 is now active.
2019-10-18T01:06:52.852+02:00 info vmware-vci-vciInstallUtils[49254] [Originator@6876 sub=WorkQueue.vmacoreDefaultIOCompletionQueue] Created: WorkQueue.vmacoreDefaultIOCompletionQueue, type = fair, priority = 4 , itemWeight = 1
2019-10-18T01:06:52.852+02:00 info vmware-vci-vciInstallUtils[49254] [Originator@6876 sub=FairScheduler] Priority level 8 is now active.
2019-10-18T01:06:52.852+02:00 info vmware-vci-vciInstallUtils[49254] [Originator@6876 sub=WorkQueue.vmacoreDefaultIOQueue] Created: WorkQueue.vmacoreDefaultIOQueue, type = fair, priority = 8 , itemWeight = 1
2019-10-18T01:06:52.852+02:00 info vmware-vci-vciInstallUtils[49254] [Originator@6876 sub=FairScheduler] Priority level 16 is now active.
2019-10-18T01:06:52.852+02:00 info vmware-vci-vciInstallUtils[49254] [Originator@6876 sub=WorkQueue.vmacoreDefaultLongTaskQueue] Created: WorkQueue.vmacoreDefaultLongTaskQueue, type = fair, priority = 16 , itemWeight = 1
2019-10-18T01:06:52.852+02:00 info vmware-vci-vciInstallUtils[49254] [Originator@6876 sub=ThreadPool] Registered foreign worker - allocated: 1, idle: 0
2019-10-18T01:06:52.852+02:00 info vmware-vci-vciInstallUtils[49254] [Originator@6876 sub=ThreadPool] Registered foreign worker - allocated: 2, idle: 0
2019-10-18T01:06:52.852+02:00 info vmware-vci-vciInstallUtils[49254] [Originator@6876 sub=ThreadPool] Registered foreign worker - allocated: 3, idle: 0
2019-10-18T01:06:52.852+02:00 info vmware-vci-vciInstallUtils[49254] [Originator@6876 sub=ThreadPool] Registered foreign worker - allocated: 4, idle: 0
2019-10-18T01:06:52.852+02:00 info vmware-vci-vciInstallUtils[49254] [Originator@6876 sub=ThreadPool] Thread pool fair initial threads spawned. IO: 2, Min workers: 4, Max workers: 13, Reservation ratio: 9
2019-10-18T01:06:52.852+02:00 info vmware-vci-vciInstallUtils[49260] [Originator@6876 sub=ThreadPool] Thread enlisted
2019-10-18T01:06:52.852+02:00 info vmware-vci-vciInstallUtils[49260] [Originator@6876 sub=ThreadPool] Entering worker thread loop
2019-10-18T01:06:52.852+02:00 info vmware-vci-vciInstallUtils[49254] [Originator@6876 sub=ThreadPool] Thread enlisted
2019-10-18T01:06:52.852+02:00 info vmware-vci-vciInstallUtils[49254] [Originator@6876 sub=Default] Syscommand enabled: true
2019-10-18T01:06:52.852+02:00 info vmware-vci-vciInstallUtils[49254] [Originator@6876 sub=Default] ReaperManager Initialized
2019-10-18T01:06:52.852+02:00 info vmware-vci-vciInstallUtils[49265] [Originator@6876 sub=ThreadPool] Thread enlisted
2019-10-18T01:06:52.852+02:00 info vmware-vci-vciInstallUtils[49265] [Originator@6876 sub=ThreadPool] Entering IO thread loop
2019-10-18T01:06:52.852+02:00 info vmware-vci-vciInstallUtils[49264] [Originator@6876 sub=ThreadPool] Thread enlisted
2019-10-18T01:06:52.853+02:00 info vmware-vci-vciInstallUtils[49264] [Originator@6876 sub=ThreadPool] Entering IO thread loop
2019-10-18T01:06:52.852+02:00 info vmware-vci-vciInstallUtils[49261] [Originator@6876 sub=ThreadPool] Thread enlisted
2019-10-18T01:06:52.853+02:00 info vmware-vci-vciInstallUtils[49261] [Originator@6876 sub=ThreadPool] Entering worker thread loop
2019-10-18T01:06:52.852+02:00 info vmware-vci-vciInstallUtils[49262] [Originator@6876 sub=ThreadPool] Thread enlisted
2019-10-18T01:06:52.853+02:00 info vmware-vci-vciInstallUtils[49262] [Originator@6876 sub=ThreadPool] Entering worker thread loop
2019-10-18T01:06:52.852+02:00 info vmware-vci-vciInstallUtils[49263] [Originator@6876 sub=ThreadPool] Thread enlisted
2019-10-18T01:06:52.853+02:00 info vmware-vci-vciInstallUtils[49263] [Originator@6876 sub=ThreadPool] Entering worker thread loop
2019-10-18T01:06:52.853+02:00 info vmware-vci-vciInstallUtils[49254] [Originator@6876 sub=Libs] DictionaryLoad: Cannot open file "/etc/vmware/config": No such file or directory.
2019-10-18T01:06:52.853+02:00 info vmware-vci-vciInstallUtils[49254] [Originator@6876 sub=Libs] [msg.dictionary.load.openFailed] Cannot open file "/etc/vmware/config": No such file or directory.
2019-10-18T01:06:52.853+02:00 info vmware-vci-vciInstallUtils[49254] [Originator@6876 sub=Libs] PREF Optional preferences file not found at /etc/vmware/config. Using default values.
2019-10-18T01:06:52.853+02:00 info vmware-vci-vciInstallUtils[49266] [Originator@6876 sub=ThreadPool] Thread enlisted
2019-10-18T01:06:52.853+02:00 info vmware-vci-vciInstallUtils[49266] [Originator@6876 sub=ThreadPool] Entering fair thread loop
2019-10-18T01:06:52.853+02:00 info vmware-vci-vciInstallUtils[49260] [Originator@6876 sub=ThreadPool] Spawning additional worker - allocated: 5, idle: 0
2019-10-18T01:06:52.853+02:00 info vmware-vci-vciInstallUtils[49267] [Originator@6876 sub=ThreadPool] Thread enlisted
2019-10-18T01:06:52.853+02:00 info vmware-vci-vciInstallUtils[49267] [Originator@6876 sub=ThreadPool] Entering worker thread loop
2019-10-18T01:06:52.853+02:00 info vmware-vci-vciInstallUtils[49254] [Originator@6876 sub=Libs] DictionaryLoad: Cannot open file "/usr/lib/vmware/settings": No such file or directory.
2019-10-18T01:06:52.853+02:00 info vmware-vci-vciInstallUtils[49254] [Originator@6876 sub=Libs] [msg.dictionary.load.openFailed] Cannot open file "/usr/lib/vmware/settings": No such file or directory.
2019-10-18T01:06:52.853+02:00 info vmware-vci-vciInstallUtils[49254] [Originator@6876 sub=Libs] PREF Optional preferences file not found at /usr/lib/vmware/settings. Using default values.
2019-10-18T01:06:52.853+02:00 info vmware-vci-vciInstallUtils[49254] [Originator@6876 sub=Libs] DictionaryLoad: Cannot open file "/usr/lib/vmware/config": No such file or directory.
2019-10-18T01:06:52.853+02:00 info vmware-vci-vciInstallUtils[49254] [Originator@6876 sub=Libs] [msg.dictionary.load.openFailed] Cannot open file "/usr/lib/vmware/config": No such file or directory.
2019-10-18T01:06:52.853+02:00 info vmware-vci-vciInstallUtils[49254] [Originator@6876 sub=Libs] PREF Optional preferences file not found at /usr/lib/vmware/config. Using default values.
2019-10-18T01:06:52.853+02:00 info vmware-vci-vciInstallUtils[49254] [Originator@6876 sub=Libs] DictionaryLoad: Cannot open file "/root/.vmware/config": No such file or directory.
2019-10-18T01:06:52.853+02:00 info vmware-vci-vciInstallUtils[49254] [Originator@6876 sub=Libs] [msg.dictionary.load.openFailed] Cannot open file "/root/.vmware/config": No such file or directory.
2019-10-18T01:06:52.853+02:00 info vmware-vci-vciInstallUtils[49254] [Originator@6876 sub=Libs] PREF Optional preferences file not found at /root/.vmware/config. Using default values.
2019-10-18T01:06:52.853+02:00 info vmware-vci-vciInstallUtils[49254] [Originator@6876 sub=Libs] DictionaryLoad: Cannot open file "/root/.vmware/preferences": No such file or directory.
2019-10-18T01:06:52.853+02:00 info vmware-vci-vciInstallUtils[49254] [Originator@6876 sub=Libs] [msg.dictionary.load.openFailed] Cannot open file "/root/.vmware/preferences": No such file or directory.
2019-10-18T01:06:52.853+02:00 info vmware-vci-vciInstallUtils[49254] [Originator@6876 sub=Libs] PREF Optional preferences file not found at /root/.vmware/preferences. Using default values.
2019-10-18T01:06:52.854+02:00 info vmware-vci-vciInstallUtils[49254] [Originator@6876 sub=Libs] lib/ssl: OpenSSL using FIPS_drbg for RAND
2019-10-18T01:06:52.854+02:00 info vmware-vci-vciInstallUtils[49254] [Originator@6876 sub=Libs] lib/ssl: protocol list tls1.2
2019-10-18T01:06:52.854+02:00 info vmware-vci-vciInstallUtils[49254] [Originator@6876 sub=Libs] lib/ssl: protocol list tls1.2 (openssl flags 0x17000000)
2019-10-18T01:06:52.854+02:00 info vmware-vci-vciInstallUtils[49254] [Originator@6876 sub=Libs] lib/ssl: cipher list ECDHE+AESGCM:RSA+AESGCM:ECDHE+AES:RSA+AES
2019-10-18T01:06:52.854+02:00 info vmware-vci-vciInstallUtils[49254] [Originator@6876 sub=Libs] lib/ssl: curves list prime256v1:secp384r1:secp521r1
2019-10-18T01:06:52.861+02:00 info vmware-vci-vciInstallUtils[49254] [Originator@6876 sub=Default] Vmacore::InitSSL: handshakeTimeoutUs = 120000000
[2019-10-18 01:06:52:862 '' 139750652217408 ALERT] [logUtil, 487] Product = VMware Update Manager, Version = 6.7.0, Build = 14203538
[2019-10-18 01:06:52:862 '' 139750652217408 INFO] [main, 400] Successfully configured logger
[2019-10-18 01:06:52:862 '' 139750652217408 INFO] [main, 419] command line arguments: argv 0 = /usr/lib/vmware-updatemgr/bin/vciInstallUtils argv 1 = -C argv 2 = /lib/vmware-updatemgr/bin/ argv 3 = -L argv 4 = /var/log/vmware/vmware-updatemgr/ argv 5 = -I argv 6 = /lib/vmware-updatemgr/bin/ argv 7 = -v argv 8 = server argv 9 = -p argv 10 = 80 argv 11 = -U argv 12 = administrator@vsphere.local argv 13 = -P argv 14 = xxxx(user password) argv 15 = -S argv 16 = /lib/vmware-updatemgr/bin/extension.xml argv 17 = -O argv 18 = extupdate
[2019-10-18 01:06:52:862 '' 139750652217408 DEBUG] [installerRunVCCommand, 107] [VCSERVER] op name = extupdate
[2019-10-18 01:06:52:862 '' 139750652217408 DEBUG] [installerRunVCCommand, 134] [VCSERVER] logging into vc.
[2019-10-18 01:06:52:862 '' 139750652217408 DEBUG] [installerRunVCCommand, 138] [VCSERVER] host = server, port = 80, user = administrator@vsphere.local
2019-10-18T01:06:52.862+02:00 info vmware-vci-vciInstallUtils[49254] [Originator@6876 sub=VciInstallUtils] Connecting to host server on port 80 using protocol http
-->
2019-10-18T01:06:52.862+02:00 verbose vmware-vci-vciInstallUtils[49254] [Originator@6876 sub=HttpConnectionPool-000000] HttpConnectionPoolImpl created. maxPoolConnections = 20; idleTimeout = 900000000; maxOpenConnections = 20; maxConnectionAge = 0
2019-10-18T01:06:52.863+02:00 info vmware-vci-vciInstallUtils[49261] [Originator@6876 sub=ThreadPool] Spawning additional worker - allocated: 6, idle: 0
2019-10-18T01:06:52.863+02:00 info vmware-vci-vciInstallUtils[49269] [Originator@6876 sub=ThreadPool] Thread enlisted
2019-10-18T01:06:52.863+02:00 info vmware-vci-vciInstallUtils[49269] [Originator@6876 sub=ThreadPool] Entering worker thread loop
2019-10-18T01:06:52.864+02:00 info vmware-vci-vciInstallUtils[49262] [Originator@6876 sub=ThreadPool] Spawning additional worker - allocated: 7, idle: 1
2019-10-18T01:06:52.864+02:00 info vmware-vci-vciInstallUtils[49270] [Originator@6876 sub=ThreadPool] Thread enlisted
2019-10-18T01:06:52.864+02:00 info vmware-vci-vciInstallUtils[49270] [Originator@6876 sub=ThreadPool] Entering worker thread loop
2019-10-18T01:06:52.864+02:00 info vmware-vci-vciInstallUtils[49262] [Originator@6876 sub=ThreadPool] Spawning additional worker - allocated: 8, idle: 2
2019-10-18T01:06:52.864+02:00 info vmware-vci-vciInstallUtils[49271] [Originator@6876 sub=ThreadPool] Thread enlisted
2019-10-18T01:06:52.866+02:00 info vmware-vci-vciInstallUtils[49271] [Originator@6876 sub=ThreadPool] Entering worker thread loop
[2019-10-18 01:06:52:867 '' 139750652217408 INFO] [installerRunVCCommand, 658] [VCSERVER] Calling RegisterExtension
[2019-10-18 01:06:52:985 'Registrar' 139750652217408 INFO] VC server URL: http://server:80
2019-10-18T01:06:52.986+02:00 info vmware-vci-vciInstallUtils[49254] [Originator@6876 sub=VciInstallUtils] Connecting to host server on port 80 using protocol http
-->
2019-10-18T01:06:53.874+02:00 info vmware-vci-vciInstallUtils[49254] [Originator@6876 sub=Extension] Connecting to host server on port 80 using protocol http
-->
2019-10-18T01:06:54.880+02:00 info vmware-vci-vciInstallUtils[49254] [Originator@6876 sub=Extension] Authenticating user administrator@vsphere.local
2019-10-18T01:06:54.880+02:00 verbose vmware-vci-vciInstallUtils[49254] [Originator@6876 sub=HttpConnectionPool-000001] HttpConnectionPoolImpl created. maxPoolConnections = 1; idleTimeout = 900000000; maxOpenConnections = 1; maxConnectionAge = 0
2019-10-18T01:06:54.880+02:00 info vmware-vci-vciInstallUtils[49269] [Originator@6876 sub=ThreadPool] Spawning additional worker - allocated: 9, idle: 1
2019-10-18T01:06:54.881+02:00 info vmware-vci-vciInstallUtils[49275] [Originator@6876 sub=ThreadPool] Thread enlisted
2019-10-18T01:06:54.881+02:00 info vmware-vci-vciInstallUtils[49275] [Originator@6876 sub=ThreadPool] Entering worker thread loop
2019-10-18T01:06:54.911+02:00 info vmware-vci-vciInstallUtils[49254] [Originator@6876 sub=Extension] Logged in!
[2019-10-18 01:06:55:332 'Registrar' 139750652217408 INFO] This extension certificate has registered.
2019-10-18T01:06:55.338+02:00 verbose vmware-vci-vciInstallUtils[49254] [Originator@6876 sub=vmomi.soapStub[2]] Resetting stub adapter for server <cs p:0000000001070420, TCP:server:80> : Closed
[2019-10-18 01:06:55:341 '' 139750652217408 INFO] [installerRunVCCommand, 669] [VCSERVER] RegisterExtension returned:0
[2019-10-18 01:06:55:343 '' 139750652217408 INFO] [installerRunVCCommand, 814] [VCSERVER] Setting the vpxdThumbprint config value in xml
[2019-10-18 01:06:55:352 '' 139750652217408 DEBUG] [installerRunVCCommand, 358] [VCSERVER] returning code = 0 from vc check.
root@server [ ~ ]# cd /lib/vmware-updatemgr/bin
root@server [ /lib/vmware-updatemgr/bin ]# chown updatemgr:updatemgr vci-integrity.xml
root@server [ /lib/vmware-updatemgr/bin ]# service-control --start vmware-updatemgr
Operation not cancellable. Please wait for it to finish...
Performing start operation on service updatemgr...
Error executing start on service updatemgr. Details {
"problemId": null,
"componentKey": null,
"detail": [
{
"translatable": "An error occurred while starting service '%(0)s'",
"args": [
"updatemgr"
],
"id": "install.ciscommon.service.failstart",
"localized": "An error occurred while starting service 'updatemgr'"
}
],
"resolution": null
}
Service-control failed. Error: {
"problemId": null,
"componentKey": null,
"detail": [
{
"translatable": "An error occurred while starting service '%(0)s'",
"args": [
"updatemgr"
],
"id": "install.ciscommon.service.failstart",
"localized": "An error occurred while starting service 'updatemgr'"
}
],
"resolution": null
Thanks for your help with this.
Daniel
OK...finaly a new VCSA is installed...all steps with no success..
/close