VMware Cloud Community
fafa24
Enthusiast
Enthusiast
Jump to solution

Vmware Virtualcenter service won't start - version 5.5

Dear All,

I'm having a hard time with my Vcenter Server. My server is at Version 5.5 for Windows and use a remote SQL server 2008 R2. Everything was working fine until I stopped all services on my Vcenter server to run the clean_orphane_MSSQL_script to prepare the database for upgrading to 6.0. The script couldn't run since there was a collation error. After that I was unable to start the Virtual center server service.

Before I performed the operation to run the script I was making a database backup and a snapshot from the Vcenter VM. I restored both, but the VirtualCenter Server service still won't start.

Below you see the vpxd.log file. It seems the Vcenter reaches the database, but there are some errors writing to tables.

Any help is highly appreciated.

Thank you!

<<<<<<<<<<<<<

Section for VMware VirtualCenter, pid=4064, version=5.5.0, build=2183111, option=Release

------ In-memory logs start --------
mem> 2016-01-20T18:51:59.093+01:00 [02780 info 'Hooks'] Hooks Initialized

------ In-memory logs end   --------
2016-01-20T18:51:59.116+01:00 [02780 info 'Default'] Logging uses fast path: true
2016-01-20T18:51:59.116+01:00 [02780 info 'Libs'] VThreadBase detected multiple threads.
2016-01-20T18:51:59.116+01:00 [02780 info 'Default'] Handling bora/lib logs with VmaCore facilities
2016-01-20T18:51:59.116+01:00 [02780 info 'Default'] Initialized channel manager
2016-01-20T18:51:59.136+01:00 [02780 info 'Default'] Current working directory: C:\Windows\system32
2016-01-20T18:51:59.136+01:00 [02780 info 'Default'] ThreadPool windowsStackImmediateCommit = true
2016-01-20T18:51:59.136+01:00 [04756 info 'ThreadPool'] Thread enlisted
2016-01-20T18:51:59.136+01:00 [05896 info 'ThreadPool'] Thread enlisted
2016-01-20T18:51:59.137+01:00 [03608 info 'ThreadPool'] Thread enlisted
2016-01-20T18:51:59.137+01:00 [02780 info 'ThreadPool'] Thread pool on asio: Min Io, Max Io, Min Task, Max Task, Max Concurency: 2, 200, 2, 30, 2147483647
2016-01-20T18:51:59.137+01:00 [02780 info 'ThreadPool'] Thread enlisted
2016-01-20T18:51:59.137+01:00 [03288 info 'ThreadPool'] Thread enlisted
2016-01-20T18:51:59.137+01:00 [02780 info 'Default'] Log path: C:\ProgramData\VMware\VMware VirtualCenter\Logs
2016-01-20T18:51:59.137+01:00 [02780 info 'Default'] Initializing SSL
2016-01-20T18:51:59.142+01:00 [02780 info 'Default'] Vmacore::InitSSL: handshakeTimeoutUs = 120000000
2016-01-20T18:51:59.143+01:00 [02840 info 'ThreadPool'] Thread enlisted
2016-01-20T18:51:59.157+01:00 [02780 info 'Default'] Starting VMware VirtualCenter 5.5.0 build-2183111
2016-01-20T18:51:59.158+01:00 [02780 info 'Default'] Log directory: C:\ProgramData\VMware\VMware VirtualCenter\Logs.
2016-01-20T18:51:59.158+01:00 [02780 info 'vpxdvpxdMain'] Account name: SYSTEM
2016-01-20T18:51:59.158+01:00 [02780 info 'win32vpxdOsLayer_win32'] Total virtual memory available for the process 8589934464 KB
2016-01-20T18:51:59.158+01:00 [02780 info 'win32vpxdOsLayer_win32'] [VpxOsLayer] Enabled low-frag process heap.
-->
2016-01-20T18:51:59.158+01:00 [02780 info 'win32vpxdOsLayer_win32'] [VpxOsLayer] Enabled low-frag crt heap.
-->
2016-01-20T18:51:59.159+01:00 [02780 info 'commonvpxLro'] [VpxLRO] 640 max LROs
2016-01-20T18:51:59.159+01:00 [02780 info 'commonvpxLro'] [VpxLRO] 12 reserved internal LROs
2016-01-20T18:51:59.159+01:00 [02780 info 'commonvpxLro'] [VpxLRO] 12 reserved blocker LROs
2016-01-20T18:51:59.159+01:00 [02780 info 'commonvpxLro'] [VpxLRO] 12 reserved short LROs
2016-01-20T18:51:59.159+01:00 [02780 info 'commonvpxLro'] [VpxLRO] 8 reserved long LROs
2016-01-20T18:51:59.159+01:00 [02780 info 'commonvpxLro'] [VpxLRO] 600-second completed task lifetime
2016-01-20T18:51:59.159+01:00 [02780 info 'commonvpxLro'] [VpxLRO] 60-second minimum completed task lifetime
2016-01-20T18:51:59.159+01:00 [02780 info 'commonvpxLro'] [VpxLRO] 200 maximum completed tasks
2016-01-20T18:51:59.159+01:00 [02780 info 'commonvpxLro'] [VpxLRO] 600-second unregistered task lifetime
2016-01-20T18:51:59.160+01:00 [02780 info 'utilvpxdVdb'] Registry Item DB 5 value is '50'
2016-01-20T18:51:59.160+01:00 [02780 info 'utilvpxdVdb'] [VpxdVdb::SetDBType] Logging in to DSN: Vmware with username vcenter
2016-01-20T18:51:59.287+01:00 [02780 info 'Default'] [VdbSchemaLoader::LookupSchemaFromDB] Loaded schema and index information from DB
2016-01-20T18:51:59.290+01:00 [02780 warning 'Default'] [VdbStatement] SQL execution failed: insert into VPX_TABLE (TABLE_NAME, COL_NAME, COL_TYPE, COL_LEN, PK_FLG) values (?, ?, ?, ?, ?)
2016-01-20T18:51:59.290+01:00 [02780 warning 'Default'] [VdbStatement] Execution elapsed time: 0 ms
2016-01-20T18:51:59.290+01:00 [02780 warning 'Default'] [VdbStatement] Diagnostic data from driver is 07002:1:0:[Microsoft][SQL Server Native Client 10.0]COUNT field incorrect or syntax error
2016-01-20T18:51:59.290+01:00 [02780 warning 'Default'] [VdbStatement] Bind parameters:
2016-01-20T18:51:59.290+01:00 [02780 error 'Default'] [Vdb::IsRecoverableErrorCode] Unable to recover from 07002:0
2016-01-20T18:51:59.290+01:00 [02780 error 'Default'] [VdbStatement] SQLError was thrown: "ODBC error: (07002) - [Microsoft][SQL Server Native Client 10.0]COUNT field incorrect or syntax error" is returned when executing SQL statement "insert into VPX_TABLE (TABLE_NAME, COL_NAME, COL_TYPE, COL_LEN, PK_FLG) values (?, ?, ?, ?, ?)"
2016-01-20T18:51:59.290+01:00 [02780 error 'Default'] [VdbSchemaLoader::StoreTableSchemaInfoToDB] Error inserting into VPX_TABLE for table VPX_VERSION: "ODBC error: (07002) - [Microsoft][SQL Server Native Client 10.0]COUNT field incorrect or syntax error" is returned when executing SQL statement "insert into VPX_TABLE (TABLE_NAME, COL_NAME, COL_TYPE, COL_LEN, PK_FLG) values (?, ?, ?, ?, ?)"
2016-01-20T18:51:59.290+01:00 [02780 error 'Default'] [VdbTableDef::PopulateSchemaInfo] Failed to retrieve column information for table VPX_VERSION. Usually this is the result of a mismatch between the DB schema and the product/build.
2016-01-20T18:52:11.161+01:00 [03288 warning 'VpxProfiler' opID=SWI-41a7] VpxUtil_InvokeWithOpId [TotalTime] took 12000 ms
2016-01-20T18:52:14.236+01:00 [02780 info 'Default'] CoreDump: Writing minidump
2016-01-20T18:52:17.051+01:00 [02780 panic 'Default']
-->
--> Panic: [VdbTableDef::PopulateSchemaInfo] Exiting. Unable to retrieve schema information.
--> Backtrace:
--> backtrace[00] rip 000000018018b7fa
--> backtrace[01] rip 0000000180104c78
--> backtrace[02] rip 0000000180105f6e
--> backtrace[03] rip 00000001801a19bc
--> backtrace[04] rip 00000001801a1b0c
--> backtrace[05] rip 000000014046e282
--> backtrace[06] rip 000000014046f13f
--> backtrace[07] rip 000000014046f2b1
--> backtrace[08] rip 0000000140486b3c
--> backtrace[09] rip 000000014048737a
--> backtrace[10] rip 000000013f66e819
--> backtrace[11] rip 000000013f6714b1
--> backtrace[12] rip 000000013f67220e
--> backtrace[13] rip 000000013f6725b8
--> backtrace[14] rip 000000013ff70ffc
--> backtrace[15] rip 000000013ff6f8cc
--> backtrace[16] rip 00000001401a526b
--> backtrace[17] rip 000007fefd79a82d
--> backtrace[18] rip 00000000773b59ed
--> backtrace[19] rip 00000000775eb831
-->
2016-01-20T18:52:17.055+01:00 [02780 panic 'Default'] (Log recursion level 2) [VdbTableDef::PopulateSchemaInfo] Exiting. Unable to retrieve schema information.

------ In-memory logs start --------
mem> 2016-01-20T18:51:59.093+01:00 [02780 info 'Hooks'] Hooks Initialized
mem> 2016-01-20T18:51:59.116+01:00 [02780 info 'Default'] Section for VMware VirtualCenter, pid=4064, version=5.5.0, build=2183111, option=Release
mem> -->
mem> 2016-01-20T18:51:59.116+01:00 [02780 info 'Default'] Logging uses fast path: true
mem> 2016-01-20T18:51:59.116+01:00 [02780 info 'Libs'] VThreadBase detected multiple threads.
mem> 2016-01-20T18:51:59.116+01:00 [02780 info 'Default'] Handling bora/lib logs with VmaCore facilities
mem> 2016-01-20T18:51:59.116+01:00 [02780 info 'Default'] Initialized channel manager
mem> 2016-01-20T18:51:59.136+01:00 [02780 info 'Default'] Current working directory: C:\Windows\system32
mem> 2016-01-20T18:51:59.136+01:00 [02780 info 'Default'] ThreadPool windowsStackImmediateCommit = true
mem> 2016-01-20T18:51:59.136+01:00 [04756 info 'ThreadPool'] Thread enlisted
mem> 2016-01-20T18:51:59.136+01:00 [05896 info 'ThreadPool'] Thread enlisted
mem> 2016-01-20T18:51:59.137+01:00 [03608 info 'ThreadPool'] Thread enlisted
mem> 2016-01-20T18:51:59.137+01:00 [02780 info 'ThreadPool'] Thread pool on asio: Min Io, Max Io, Min Task, Max Task, Max Concurency: 2, 200, 2, 30, 2147483647
mem> 2016-01-20T18:51:59.137+01:00 [02780 info 'ThreadPool'] Thread enlisted
mem> 2016-01-20T18:51:59.137+01:00 [03288 info 'ThreadPool'] Thread enlisted
mem> 2016-01-20T18:51:59.137+01:00 [02780 info 'Default'] Log path: C:\ProgramData\VMware\VMware VirtualCenter\Logs
mem> 2016-01-20T18:51:59.137+01:00 [02780 info 'Default'] Initializing SSL
mem> 2016-01-20T18:51:59.142+01:00 [02780 info 'Default'] Vmacore::InitSSL: handshakeTimeoutUs = 120000000
mem> 2016-01-20T18:51:59.143+01:00 [02840 info 'ThreadPool'] Thread enlisted
mem> 2016-01-20T18:51:59.146+01:00 [02780 verbose 'vpxdvpxdSupportManager'] Uptime Log: C:\ProgramData\VMware\VMware VirtualCenter\Logs/1453312319146329.vpxduptime
mem> 2016-01-20T18:51:59.150+01:00 [02780 verbose 'vpxdvpxdSupportManager'] Moved 1 uptime log(s) to cache dir
mem> 2016-01-20T18:51:59.150+01:00 [02780 verbose 'vpxdvpxdSupportManager'] Recd:1453312319146329|BMZURNT021.bakernet.com|03f28f09-576c-41cb-a149-58565ad925e9|build-2183111|10|:
mem> 2016-01-20T18:51:59.150+01:00 [02780 verbose 'vpxdvpxdSupportManager'] Initing Support Manager
mem> 2016-01-20T18:51:59.150+01:00 [02780 verbose 'vpxdvpxdSupportManager'] Support Manager Monitor invoked
mem> 2016-01-20T18:51:59.157+01:00 [02780 info 'Default'] Starting VMware VirtualCenter 5.5.0 build-2183111
mem> 2016-01-20T18:51:59.158+01:00 [02780 info 'Default'] Log directory: C:\ProgramData\VMware\VMware VirtualCenter\Logs.
mem> 2016-01-20T18:51:59.158+01:00 [02780 info 'vpxdvpxdMain'] Account name: SYSTEM
mem> 2016-01-20T18:51:59.158+01:00 [02780 info 'win32vpxdOsLayer_win32'] Total virtual memory available for the process 8589934464 KB
mem> 2016-01-20T18:51:59.158+01:00 [02780 info 'win32vpxdOsLayer_win32'] [VpxOsLayer] Enabled low-frag process heap.
mem> -->
mem> 2016-01-20T18:51:59.158+01:00 [02780 info 'win32vpxdOsLayer_win32'] [VpxOsLayer] Enabled low-frag crt heap.
mem> -->
mem> 2016-01-20T18:51:59.159+01:00 [02780 info 'commonvpxLro'] [VpxLRO] 640 max LROs
mem> 2016-01-20T18:51:59.159+01:00 [02780 info 'commonvpxLro'] [VpxLRO] 12 reserved internal LROs
mem> 2016-01-20T18:51:59.159+01:00 [02780 info 'commonvpxLro'] [VpxLRO] 12 reserved blocker LROs
mem> 2016-01-20T18:51:59.159+01:00 [02780 info 'commonvpxLro'] [VpxLRO] 12 reserved short LROs
mem> 2016-01-20T18:51:59.159+01:00 [02780 info 'commonvpxLro'] [VpxLRO] 8 reserved long LROs
mem> 2016-01-20T18:51:59.159+01:00 [02780 info 'commonvpxLro'] [VpxLRO] 600-second completed task lifetime
mem> 2016-01-20T18:51:59.159+01:00 [02780 info 'commonvpxLro'] [VpxLRO] 60-second minimum completed task lifetime
mem> 2016-01-20T18:51:59.159+01:00 [02780 info 'commonvpxLro'] [VpxLRO] 200 maximum completed tasks
mem> 2016-01-20T18:51:59.159+01:00 [02780 info 'commonvpxLro'] [VpxLRO] 600-second unregistered task lifetime
mem> 2016-01-20T18:51:59.159+01:00 [02780 verbose 'ThreadPool'] [SetConfig] Thread info: IoMin: 2, IoMax: 1930, TaskMin: 59, TaskMax: 1930
mem> 2016-01-20T18:51:59.160+01:00 [02780 info 'utilvpxdVdb'] Registry Item DB 5 value is '50'
mem> 2016-01-20T18:51:59.160+01:00 [02780 info 'utilvpxdVdb'] [VpxdVdb::SetDBType] Logging in to DSN: Vmware with username vcenter
mem> 2016-01-20T18:51:59.193+01:00 [02780 verbose 'Default'] [Vdb] Connecting to database type: (Microsoft SQL Server)
mem> 2016-01-20T18:51:59.282+01:00 [02780 verbose 'utilvpxdVdb'] [VpxdVdb::CheckSchemaNameRequirement] Set schema name: db_owner
mem> 2016-01-20T18:51:59.282+01:00 [02780 verbose 'utilvpxdVdb'] [VpxdVdb::InitImpl] Connected to DSN Vmware
mem> 2016-01-20T18:51:59.286+01:00 [02780 verbose 'utilvpxdVdb'] Successfully locked vCenter repository
mem> 2016-01-20T18:51:59.287+01:00 [02780 info 'Default'] [VdbSchemaLoader::LookupSchemaFromDB] Loaded schema and index information from DB
mem> 2016-01-20T18:51:59.287+01:00 [02780 verbose 'Default'] [VdbSchemaLoader::LookupSchema] Found schema/index information for: VPX_VERSION
mem> 2016-01-20T18:51:59.290+01:00 [02780 warning 'Default'] [VdbStatement] SQL execution failed: insert into VPX_TABLE (TABLE_NAME, COL_NAME, COL_TYPE, COL_LEN, PK_FLG) values (?, ?, ?, ?, ?)
mem> 2016-01-20T18:51:59.290+01:00 [02780 warning 'Default'] [VdbStatement] Execution elapsed time: 0 ms
mem> 2016-01-20T18:51:59.290+01:00 [02780 warning 'Default'] [VdbStatement] Diagnostic data from driver is 07002:1:0:[Microsoft][SQL Server Native Client 10.0]COUNT field incorrect or syntax error
mem> 2016-01-20T18:51:59.290+01:00 [02780 warning 'Default'] [VdbStatement] Bind parameters:
mem> 2016-01-20T18:51:59.290+01:00 [02780 error 'Default'] [Vdb::IsRecoverableErrorCode] Unable to recover from 07002:0
mem> 2016-01-20T18:51:59.290+01:00 [02780 error 'Default'] [VdbStatement] SQLError was thrown: "ODBC error: (07002) - [Microsoft][SQL Server Native Client 10.0]COUNT field incorrect or syntax error" is returned when executing SQL statement "insert into VPX_TABLE (TABLE_NAME, COL_NAME, COL_TYPE, COL_LEN, PK_FLG) values (?, ?, ?, ?, ?)"
mem> 2016-01-20T18:51:59.290+01:00 [02780 error 'Default'] [VdbSchemaLoader::StoreTableSchemaInfoToDB] Error inserting into VPX_TABLE for table VPX_VERSION: "ODBC error: (07002) - [Microsoft][SQL Server Native Client 10.0]COUNT field incorrect or syntax error" is returned when executing SQL statement "insert into VPX_TABLE (TABLE_NAME, COL_NAME, COL_TYPE, COL_LEN, PK_FLG) values (?, ?, ?, ?, ?)"
mem> 2016-01-20T18:51:59.290+01:00 [02780 error 'Default'] [VdbTableDef::PopulateSchemaInfo] Failed to retrieve column information for table VPX_VERSION. Usually this is the result of a mismatch between the DB schema and the product/build.
mem> 2016-01-20T18:52:17.055+01:00 [02780 panic 'Default'] (Log recursion level 2) [VdbTableDef::PopulateSchemaInfo] Exiting. Unable to retrieve schema information.

------ In-memory logs end   --------
2016-01-20T18:52:17.055+01:00 [02780 panic 'Default'] Section for VMware VirtualCenter, pid=4064, version=5.5.0, build=2183111, option=Release
-->
2016-01-20T18:52:17.055+01:00 [02780 info 'vpxdvpxdSupportManager'] Wrote uptime information

Reply
0 Kudos
1 Solution

Accepted Solutions
rcporto
Leadership
Leadership
Jump to solution

Check if the database permission was restored too, take a look at this KB article: VMware KB: VMware vCenter Server login fails and panic halt occurs with the error: "Unable to retrie...

---

Richardson Porto
Senior Infrastructure Specialist
LinkedIn: http://linkedin.com/in/richardsonporto

View solution in original post

Reply
0 Kudos
2 Replies
rcporto
Leadership
Leadership
Jump to solution

Check if the database permission was restored too, take a look at this KB article: VMware KB: VMware vCenter Server login fails and panic halt occurs with the error: "Unable to retrie...

---

Richardson Porto
Senior Infrastructure Specialist
LinkedIn: http://linkedin.com/in/richardsonporto
Reply
0 Kudos
fafa24
Enthusiast
Enthusiast
Jump to solution

Thank you - it appeared the database permission was not restored too. The article helped me a lot. In the meantime I upgraded to Vsphere 6.0 (latest build) successfully.

Many thanks!

Reply
0 Kudos