11-05-2019 03:30 AM
MS SQL 2008R2 retore failed every time.
========================================================
Nov 5, 2019 4:51:00 PM - begin Restore
Nov 5, 2019 4:51:02 PM - Info dbclient (pid=5584) INF - RESTORE STARTED USING
Nov 5, 2019 4:51:02 PM - Info dbclient (pid=5584) Microsoft SQL Server 2008 R2 (SP3-GDR) (KB4057113) - 10.50.6560.0 (X64)
Nov 5, 2019 4:51:02 PM - Info dbclient (pid=5584) Dec 28 2017 15:03:48
Nov 5, 2019 4:51:02 PM - Info dbclient (pid=5584) Copyright (c) Microsoft Corporation
Nov 5, 2019 4:51:02 PM - Info dbclient (pid=5584) Enterprise Edition (64-bit) on Windows NT 6.1 <X64> (Build 7601: Service Pack 1)
Nov 5, 2019 4:51:02 PM - Info dbclient (pid=5584) Batch = C:\Program Files\Veritas\NetBackup\DbExt\MsSql\logi.bch, Op# = 1.
Nov 5, 2019 4:51:04 PM - Info dbclient (pid=5584) INF - Using backup image HPPKLDB03.MSSQL7.HPPKLSQLCL02.db.Logistics.~.7.001of001.20191031084659..C
Nov 5, 2019 4:51:04 PM - Info dbclient (pid=5584) INF - restore database "Logistics" from VIRTUAL_DEVICE='VNBU0-5584-8452-1572952862' with stats = 10, blocksize = 65536, maxtransfersize = 4194304, buffercount = 2, replace , norecovery, move 'Logistics' to 'C:\Program Files\Microsoft SQL Server\MSSQL10_50.MSSQLSERVER\MSSQL\DATA\Logistics.mdf', move 'Logistics_log' to 'C:\Program Files\Microsoft SQL Server\MSSQL10_50.MSSQLSERVER\MSSQL\DATA\Logistics_log.ldf'
Nov 5, 2019 4:51:05 PM - Info dbclient (pid=5584) INF - Number of stripes: 1, Number of buffers per stripe 2.
Nov 5, 2019 4:51:07 PM - Info dbclient (pid=5584) INF - Created VDI object for SQL Server instance <HPPKLSQLCL01>. Connection timeout is <300> seconds.
Nov 5, 2019 4:56:12 PM - Info dbclient (pid=5584) ERR - Error in GetConfiguration: 0x80770003.
Nov 5, 2019 4:56:13 PM - Info dbclient (pid=5584) CONTINUATION: - The api was waiting and the timeout interval had elapsed.
Nov 5, 2019 4:56:15 PM - Info dbclient (pid=5584) DBMS MSG - ODBC return code <-1>, SQL State <37000>, SQL Message <3201><[Microsoft][SQL Server Native Client 10.0][SQL Server]Cannot open backup device 'VNBU0-5584-8452-1572952862'. Operating system error 0x80070002(The system cannot find the file specified.).>.
Nov 5, 2019 4:56:16 PM - Info dbclient (pid=5584) DBMS MSG - SQL Message <3013><[Microsoft][SQL Server Native Client 10.0][SQL Server]RESTORE DATABASE is terminating abnormally.>
Nov 5, 2019 4:56:17 PM - Info dbclient (pid=5584) ERR - Error found executing <restore database "Logistics" from VIRTUAL_DEVICE='VNBU0-5584-8452-1572952862' with stats = 10, blocksize = 65536, maxtransfersize = 4194304, buffercount = 2, replace , norecovery, move 'Logistics' to 'C:\Program Files\Microsoft SQL Server\MSSQL10_50.MSSQLSERVER\MSSQL\DATA\Logistics.mdf', move 'Logistics_log' to 'C:\Program Files\Microsoft SQL Server\MSSQL10_50.MSSQLSERVER\MSSQL\DATA\Logistics_log.ldf'>.
Nov 5, 2019 4:56:24 PM - Info dbclient (pid=5584) INF - OPERATION #1 of batch C:\Program Files\Veritas\NetBackup\DbExt\MsSql\logi.bch FAILED with STATUS 1 (0 is normal). Elapsed time = 323(323) seconds.
Nov 5, 2019 4:56:27 PM - Info dbclient (pid=5584) INF - Results of executing <C:\Program Files\Veritas\NetBackup\DbExt\MsSql\logi.bch>:
Nov 5, 2019 4:56:27 PM - Info dbclient (pid=5584) <0> operations succeeded. <2> operations failed.
===========================================================
11-05-2019 03:39 AM
The dbclient log does not match the Job Details.
We need to see details of PID 5584.
Please also confirm that client logging level is minimum of 3.
11-05-2019 04:53 AM
Thanks for quick reply.........
Nov 5, 2019 5:57:24 PM - begin Restore
Nov 5, 2019 5:57:26 PM - Info dbclient (pid=6384) INF - RESTORE STARTED USING
Nov 5, 2019 5:57:26 PM - Info dbclient (pid=6384) Microsoft SQL Server 2008 R2 (SP3-GDR) (KB4057113) - 10.50.6560.0 (X64)
Nov 5, 2019 5:57:26 PM - Info dbclient (pid=6384) Dec 28 2017 15:03:48
Nov 5, 2019 5:57:26 PM - Info dbclient (pid=6384) Copyright (c) Microsoft Corporation
Nov 5, 2019 5:57:26 PM - Info dbclient (pid=6384) Enterprise Edition (64-bit) on Windows NT 6.1 <X64> (Build 7601: Service Pack 1)
Nov 5, 2019 5:57:26 PM - Info dbclient (pid=6384) Batch = C:\Program Files\Veritas\NetBackup\DbExt\MsSql\logi.bch, Op# = 1.
Nov 5, 2019 5:57:27 PM - Info dbclient (pid=6384) INF - Using backup image HPPKLDB03.MSSQL7.HPPKLSQLCL02.db.Logistics.~.7.001of001.20191031084659..C
Nov 5, 2019 5:57:28 PM - Info dbclient (pid=6384) INF - restore database "Logistics" from VIRTUAL_DEVICE='VNBU0-6384-6120-1572956846' with stats = 10, blocksize = 65536, maxtransfersize = 4194304, buffercount = 2, replace , norecovery, move 'Logistics' to 'C:\Program Files\Microsoft SQL Server\MSSQL10_50.MSSQLSERVER\MSSQL\DATA\Logistics.mdf', move 'Logistics_log' to 'C:\Program Files\Microsoft SQL Server\MSSQL10_50.MSSQLSERVER\MSSQL\DATA\Logistics_log.ldf'
Nov 5, 2019 5:57:29 PM - Info dbclient (pid=6384) INF - Number of stripes: 1, Number of buffers per stripe 2.
Nov 5, 2019 5:57:30 PM - Info dbclient (pid=6384) INF - Created VDI object for SQL Server instance <HPPKLSQLCL01>. Connection timeout is <300> seconds.
Nov 5, 2019 6:02:35 PM - Info dbclient (pid=6384) ERR - Error in GetConfiguration: 0x80770003.
Nov 5, 2019 6:02:36 PM - Info dbclient (pid=6384) CONTINUATION: - The api was waiting and the timeout interval had elapsed.
Nov 5, 2019 6:02:38 PM - Info dbclient (pid=6384) DBMS MSG - ODBC return code <-1>, SQL State <37000>, SQL Message <3201><[Microsoft][SQL Server Native Client 10.0][SQL Server]Cannot open backup device 'VNBU0-6384-6120-1572956846'. Operating system error 0x80070002(The system cannot find the file specified.).>.
Nov 5, 2019 6:02:39 PM - Info dbclient (pid=6384) DBMS MSG - SQL Message <3013><[Microsoft][SQL Server Native Client 10.0][SQL Server]RESTORE DATABASE is terminating abnormally.>
Nov 5, 2019 6:02:40 PM - Info dbclient (pid=6384) ERR - Error found executing <restore database "Logistics" from VIRTUAL_DEVICE='VNBU0-6384-6120-1572956846' with stats = 10, blocksize = 65536, maxtransfersize = 4194304, buffercount = 2, replace , norecovery, move 'Logistics' to 'C:\Program Files\Microsoft SQL Server\MSSQL10_50.MSSQLSERVER\MSSQL\DATA\Logistics.mdf', move 'Logistics_log' to 'C:\Program Files\Microsoft SQL Server\MSSQL10_50.MSSQLSERVER\MSSQL\DATA\Logistics_log.ldf'>.
Nov 5, 2019 6:02:47 PM - Info dbclient (pid=6384) INF - OPERATION #1 of batch C:\Program Files\Veritas\NetBackup\DbExt\MsSql\logi.bch FAILED with STATUS 1 (0 is normal). Elapsed time = 322(322) seconds.
Nov 5, 2019 6:02:50 PM - Info dbclient (pid=6384) INF - Results of executing <C:\Program Files\Veritas\NetBackup\DbExt\MsSql\logi.bch>:
Nov 5, 2019 6:02:50 PM - Info dbclient (pid=6384) <0> operations succeeded. <2> operations failed.
11-05-2019 04:59 AM - edited 11-05-2019 05:01 AM
Global logging level was on minimum logging now I have set the same on 3
11-05-2019 08:50 PM
Hi,
Can you please check the path , mentioned in the script is right and valid ? As i am seeing some errors as below :
13:42:30.449 [1084.7452] <16> CODBCaccess::LogODBCerr: DBMS MSG - ODBC return code <-1>, SQL State <37000>, SQL Message <3201><[Microsoft][SQL Server Native Client 10.0][SQL Server]Cannot open backup device 'VNBU0-1084-7452-1572941239'. Operating system error 0x80070002(The system cannot find the file specified.).>.
replace , norecovery, move 'GV' to 'C:\Program Files\Microsoft SQL Server\MSSQL10_50.MSSQLSERVER\MSSQL\DATA\ARMS.mdf', move 'GV_log' to 'C:\Program Files\Microsoft SQL Server\MSSQL10_50.MSSQLSERVER\MSSQL\DATA\ARMS_1.ldf'>.
11-06-2019 03:13 AM
I was hoping to see more in level 3 or higher dbclient log.
I have found similar errors online :
ERR - Error in GetConfiguration: 0x80770003.
CONTINUATION: - The api was waiting and the timeout interval had elapsed.
DBMS MSG - ODBC return code <-1>, SQL State <37000>, SQL Message <3101><[Microsoft][ODBC SQL Server Driver][SQL Server]
With this additional info that we do not see in your logs:
Exclusive access could not be obtained because the database is in use
So, I am missing a real reason from MS-SQL for the "SQL State <37000>, SQL Message <3101>" errors.
I have see these errors in logs that you provided for which there is no accompanying Job Details:
14:25:05.617 [2320.7428] <16> CODBCaccess::LogODBCerr: DBMS MSG - ODBC return code <-1>, SQL State <42000>, SQL Message <229><[Microsoft][SQL Server Native Client 10.0][SQL Server]The EXECUTE permission was denied on the object 'xp_loginconfig', database 'mssqlsystemresource', schema 'sys'.>.
14:25:05.617 [2320.7428] <16> CGlobalInformation::QuerySQLServerInfo: ERR - Error found executing <exec master.dbo.xp_loginconfig 'login mode'>
Can you please double-check DB credentials of the user performing the restore?
Have you checked SQL Application Logs?
SQL VDI logs?
SQL Errorlog?