Manual Backup of SQL

Saqib_Alam · ‎06-19-2012

When i try to initate manual backup of SQL job was stuck and dont complete, i get the following Error.But when job was run automatically its completed successfully.

6/19/2012 11:36:28 AM - Info nbjm(pid=7072) starting backup job (jobid=635) for client LYNCSQLSRV, policy LYNC_SQLSRV_LOGS, schedule LOGS_HOURLY
6/19/2012 11:36:28 AM - Info nbjm(pid=7072) requesting MEDIA_SERVER_WITH_ATTRIBUTES resources from RB for backup job (jobid=635, request id:{3FC9CC08-54B8-4A78-8690-8C0506766CF1})
6/19/2012 11:36:28 AM - requesting resource SQL_Media
6/19/2012 11:36:28 AM - requesting resource bs-itu01-001.kacst.ksa.NBU_CLIENT.MAXJOBS.LYNCSQLSRV
6/19/2012 11:36:28 AM - requesting resource bs-itu01-001.kacst.ksa.NBU_POLICY.MAXJOBS.LYNC_SQLSRV_LOGS
6/19/2012 11:36:28 AM - granted resource bs-itu01-001.kacst.ksa.NBU_CLIENT.MAXJOBS.LYNCSQLSRV
6/19/2012 11:36:28 AM - granted resource bs-itu01-001.kacst.ksa.NBU_POLICY.MAXJOBS.LYNC_SQLSRV_LOGS
6/19/2012 11:36:28 AM - granted resource sq-itu01-001-hcart3-robot-tld-0
6/19/2012 11:36:28 AM - estimated 0 Kbytes needed
6/19/2012 11:36:28 AM - Info nbjm(pid=7072) started backup (backupid=LYNCSQLSRV_1340094988) job for client LYNCSQLSRV, policy LYNC_SQLSRV_LOGS, schedule LOGS_HOURLY on storage unit sq-itu01-001-hcart3-robot-tld-0
6/19/2012 11:36:29 AM - started process bpbrm (4700)
6/19/2012 11:36:31 AM - Info bpbrm(pid=4700) LYNCSQLSRV is the host to backup data from
6/19/2012 11:36:31 AM - Info bpbrm(pid=4700) reading file list from client
6/19/2012 11:36:31 AM - connecting
6/19/2012 11:36:34 AM - Info bpbrm(pid=4700) starting bphdb on client
6/19/2012 11:36:34 AM - connected; connect time: 00:00:03
6/19/2012 11:36:38 AM - Info bphdb(pid=1444) Backup started
6/19/2012 11:36:40 AM - Info dbclient(pid=880) INF - BACKUP STARTED USING
6/19/2012 11:36:40 AM - Info dbclient(pid=880) Microsoft SQL Server 2008 R2 (RTM) - 10.50.1600.1 (X64)
6/19/2012 11:36:40 AM - Info dbclient(pid=880) Apr 2 2010 15:48:46
6/19/2012 11:36:40 AM - Info dbclient(pid=880) Copyright (c) Microsoft Corporation
6/19/2012 11:36:40 AM - Info dbclient(pid=880) Enterprise Edition (64-bit) on Windows NT 6.1 <X64> (Build 7601: Service Pack 1)
6/19/2012 11:36:40 AM - Info dbclient(pid=880) Batch = C:\Program Files\Veritas\NetBackup\DbExt\MsSql\LYNC_SQLSRV_LOGS.bch, Op# = 1
6/19/2012 11:36:40 AM - Info dbclient(pid=880) INF - Using backup image SQ-ITU01-005.MSSQL7.LYNCSQLSRV\LYNCINSTANCE.trx.ASPnetdb.~.7.001of001.20120619113639..C
6/19/2012 11:36:40 AM - Info dbclient(pid=880) INF - backup log "ASPnetdb" to VIRTUAL_DEVICE='VNBU0-880-8920-1340095000' with stats = 10, blocksize = 65536, maxtransfersize = 4194304, buffercount = 2
6/19/2012 11:36:40 AM - Info dbclient(pid=880) INF - Number of stripes: 1, Number of buffers per stripe 2.
6/19/2012 11:36:40 AM - Info dbclient(pid=880) INF - Created VDI object for SQL Server instance <LYNCINSTANCE>. Connection timeout is <300> seconds.
6/19/2012 11:41:43 AM - Info dbclient(pid=880) ERR - Error in GetConfiguration: 0x80770003.
6/19/2012 11:41:43 AM - Info dbclient(pid=880)     CONTINUATION: - The api was waiting and the timeout interval had elapsed.
6/19/2012 11:41:48 AM - Info dbclient(pid=880) ERR - Error in VDS->Close: 0x80770004.
6/19/2012 11:41:48 AM - Info dbclient(pid=880)     CONTINUATION: - An abort request is preventing anything except termination actions.
6/19/2012 11:41:48 AM - Info dbclient(pid=880) INF - OPERATION #1 of batch C:\Program Files\Veritas\NetBackup\DbExt\MsSql\LYNC_SQLSRV_LOGS.bch FAILED with STATUS 1 (0 is normal). Elapsed time = 309(309) seconds.
6/19/2012 11:42:01 AM - Info dbclient(pid=880) INF - BACKUP STARTED USING
6/19/2012 11:42:01 AM - Info dbclient(pid=880) Microsoft SQL Server 2008 R2 (RTM) - 10.50.1600.1 (X64)
6/19/2012 11:42:01 AM - Info dbclient(pid=880) Apr 2 2010 15:48:46
6/19/2012 11:42:01 AM - Info dbclient(pid=880) Copyright (c) Microsoft Corporation
6/19/2012 11:42:01 AM - Info dbclient(pid=880) Enterprise Edition (64-bit) on Windows NT 6.1 <X64> (Build 7601: Service Pack 1)
6/19/2012 11:42:01 AM - Info dbclient(pid=880) Batch = C:\Program Files\Veritas\NetBackup\DbExt\MsSql\LYNC_SQLSRV_LOGS.bch, Op# = 2
6/19/2012 11:42:01 AM - Info dbclient(pid=880) INF - Using backup image SQ-ITU01-005.MSSQL7.LYNCSQLSRV\LYNCINSTANCE.trx.cpsdyn.~.7.001of001.20120619114200..C
6/19/2012 11:42:01 AM - Info dbclient(pid=880) INF - backup log "cpsdyn" to VIRTUAL_DEVICE='VNBU0-880-8608-1340095321' with stats = 10, blocksize = 65536, maxtransfersize = 4194304, buffercount = 2
6/19/2012 11:42:01 AM - Info dbclient(pid=880) INF - Number of stripes: 1, Number of buffers per stripe 2.
6/19/2012 11:42:01 AM - Info dbclient(pid=880) INF - Created VDI object for SQL Server instance <LYNCINSTANCE>. Connection timeout is <300> seconds.
6/19/2012 11:47:02 AM - Info dbclient(pid=880) ERR - Error in GetConfiguration: 0x80770003.
6/19/2012 11:47:02 AM - Info dbclient(pid=880)     CONTINUATION: - The api was waiting and the timeout interval had elapsed.
6/19/2012 11:47:07 AM - Info dbclient(pid=880) ERR - Error in VDS->Close: 0x80770004.
6/19/2012 11:47:07 AM - Info dbclient(pid=880)     CONTINUATION: - An abort request is preventing anything except termination actions.
6/19/2012 11:47:07 AM - Info dbclient(pid=880) INF - OPERATION #2 of batch C:\Program Files\Veritas\NetBackup\DbExt\MsSql\LYNC_SQLSRV_LOGS.bch FAILED with STATUS 1 (0 is normal). Elapsed time = 307(307) seconds.
6/19/2012 11:47:20 AM - Info dbclient(pid=880) USER - Operation inhibited by NetBackup for Microsoft SQL Server: Only a full or incremental database backup can be performed on database <GDIP_DB> because it uses the simple recovery model or has 'truncate log on checkpoint' set.
6/19/2012 11:47:22 AM - Info dbclient(pid=880) INF - OPERATION #3 of batch C:\Program Files\Veritas\NetBackup\DbExt\MsSql\LYNC_SQLSRV_LOGS.bch FAILED with STATUS 1 (0 is normal). Elapsed time = 3(3) seconds.
6/19/2012 11:47:35 AM - Info dbclient(pid=880) INF - BACKUP STARTED USING
6/19/2012 11:47:36 AM - Info dbclient(pid=880) Microsoft SQL Server 2008 R2 (RTM) - 10.50.1600.1 (X64)
6/19/2012 11:47:36 AM - Info dbclient(pid=880) Apr 2 2010 15:48:46
6/19/2012 11:47:36 AM - Info dbclient(pid=880) Copyright (c) Microsoft Corporation
6/19/2012 11:47:36 AM - Info dbclient(pid=880) Enterprise Edition (64-bit) on Windows NT 6.1 <X64> (Build 7601: Service Pack 1)
6/19/2012 11:47:36 AM - Info dbclient(pid=880) Batch = C:\Program Files\Veritas\NetBackup\DbExt\MsSql\LYNC_SQLSRV_LOGS.bch, Op# = 4
6/19/2012 11:47:36 AM - Info dbclient(pid=880) INF - Using backup image SQ-ITU01-005.MSSQL7.LYNCSQLSRV\LYNCINSTANCE.trx.IP.~.7.001of001.20120619114734..C
6/19/2012 11:47:36 AM - Info dbclient(pid=880) INF - backup log "IP" to VIRTUAL_DEVICE='VNBU0-880-4752-1340095656' with stats = 10, blocksize = 65536, maxtransfersize = 4194304, buffercount = 2
6/19/2012 11:47:36 AM - Info dbclient(pid=880) INF - Number of stripes: 1, Number of buffers per stripe 2.
6/19/2012 11:47:36 AM - Info dbclient(pid=880) INF - Created VDI object for SQL Server instance <LYNCINSTANCE>. Connection timeout is <300> seconds.
6/19/2012 11:52:36 AM - Info dbclient(pid=880) ERR - Error in GetConfiguration: 0x80770003.
6/19/2012 11:52:36 AM - Info dbclient(pid=880)     CONTINUATION: - The api was waiting and the timeout interval had elapsed.
6/19/2012 11:52:41 AM - Info dbclient(pid=880) ERR - Error in VDS->Close: 0x80770004.
6/19/2012 11:52:41 AM - Info dbclient(pid=880)     CONTINUATION: - An abort request is preventing anything except termination actions.
6/19/2012 11:52:41 AM - Info dbclient(pid=880) INF - OPERATION #4 of batch C:\Program Files\Veritas\NetBackup\DbExt\MsSql\LYNC_SQLSRV_LOGS.bch FAILED with STATUS 1 (0 is normal). Elapsed time = 307(307) seconds.
6/19/2012 11:52:55 AM - Info dbclient(pid=880) INF - BACKUP STARTED USING
6/19/2012 11:52:55 AM - Info dbclient(pid=880) Microsoft SQL Server 2008 R2 (RTM) - 10.50.1600.1 (X64)
6/19/2012 11:52:55 AM - Info dbclient(pid=880) Apr 2 2010 15:48:46
6/19/2012 11:52:55 AM - Info dbclient(pid=880) Copyright (c) Microsoft Corporation
6/19/2012 11:52:55 AM - Info dbclient(pid=880) Enterprise Edition (64-bit) on Windows NT 6.1 <X64> (Build 7601: Service Pack 1)
6/19/2012 11:52:55 AM - Info dbclient(pid=880) Batch = C:\Program Files\Veritas\NetBackup\DbExt\MsSql\LYNC_SQLSRV_LOGS.bch, Op# = 5
6/19/2012 11:52:55 AM - Info dbclient(pid=880) INF - Using backup image SQ-ITU01-005.MSSQL7.LYNCSQLSRV\LYNCINSTANCE.trx.IT_eSERV_TAKREEM.~.7.001of001.20120619115254..C
6/19/2012 11:52:55 AM - Info dbclient(pid=880) INF - backup log "IT_eSERV_TAKREEM" to VIRTUAL_DEVICE='VNBU0-880-9056-1340095975' with stats = 10, blocksize = 65536, maxtransfersize = 4194304, buffercount = 2
6/19/2012 11:52:55 AM - Info dbclient(pid=880) INF - Number of stripes: 1, Number of buffers per stripe 2.
6/19/2012 11:52:55 AM - Info dbclient(pid=880) INF - Created VDI object for SQL Server instance <LYNCINSTANCE>. Connection timeout is <300> seconds.
6/19/2012 11:53:28 AM - Info dbclient(pid=880) INF - Thread has been closed for stripe #0
6/19/2012 11:53:36 AM - Info dbclient(pid=880) INF - OPERATION #5 of batch C:\Program Files\Veritas\NetBackup\DbExt\MsSql\LYNC_SQLSRV_LOGS.bch SUCCEEDED with STATUS 0 (0 is normal). Elapsed time = 42(1) seconds
6/19/2012 11:53:50 AM - Info dbclient(pid=880) INF - BACKUP STARTED USING
6/19/2012 11:53:50 AM - Info dbclient(pid=880) Microsoft SQL Server 2008 R2 (RTM) - 10.50.1600.1 (X64)
6/19/2012 11:53:50 AM - Info dbclient(pid=880) Apr 2 2010 15:48:46
6/19/2012 11:53:50 AM - Info dbclient(pid=880) Copyright (c) Microsoft Corporation
6/19/2012 11:53:50 AM - Info dbclient(pid=880) Enterprise Edition (64-bit) on Windows NT 6.1 <X64> (Build 7601: Service Pack 1)
6/19/2012 11:53:50 AM - Info dbclient(pid=880) Batch = C:\Program Files\Veritas\NetBackup\DbExt\MsSql\LYNC_SQLSRV_LOGS.bch, Op# = 6
6/19/2012 11:53:50 AM - Info dbclient(pid=880) INF - Using backup image SQ-ITU01-005.MSSQL7.LYNCSQLSRV\LYNCINSTANCE.trx.KACSTSERV.~.7.001of001.20120619115348..C
6/19/2012 11:53:50 AM - Info dbclient(pid=880) INF - backup log "KACSTSERV" to VIRTUAL_DEVICE='VNBU0-880-8496-1340096030' with stats = 10, blocksize = 65536, maxtransfersize = 4194304, buffercount = 2
6/19/2012 11:53:50 AM - Info dbclient(pid=880) INF - Number of stripes: 1, Number of buffers per stripe 2.
6/19/2012 11:53:50 AM - Info dbclient(pid=880) INF - Created VDI object for SQL Server instance <LYNCINSTANCE>. Connection timeout is <300> seconds.
6/19/2012 11:54:40 AM - Info dbclient(pid=880) INF - Thread has been closed for stripe #0
6/19/2012 11:54:48 AM - Info dbclient(pid=880) INF - OPERATION #6 of batch C:\Program Files\Veritas\NetBackup\DbExt\MsSql\LYNC_SQLSRV_LOGS.bch SUCCEEDED with STATUS 0 (0 is normal). Elapsed time = 60(2) seconds
6/19/2012 11:55:02 AM - Info dbclient(pid=880) INF - BACKUP STARTED USING
6/19/2012 11:55:02 AM - Info dbclient(pid=880) Microsoft SQL Server 2008 R2 (RTM) - 10.50.1600.1 (X64)
6/19/2012 11:55:02 AM - Info dbclient(pid=880) Apr 2 2010 15:48:46
6/19/2012 11:55:02 AM - Info dbclient(pid=880) Copyright (c) Microsoft Corporation
6/19/2012 11:55:02 AM - Info dbclient(pid=880) Enterprise Edition (64-bit) on Windows NT 6.1 <X64> (Build 7601: Service Pack 1)
6/19/2012 11:55:02 AM - Info dbclient(pid=880) Batch = C:\Program Files\Veritas\NetBackup\DbExt\MsSql\LYNC_SQLSRV_LOGS.bch, Op# = 7
6/19/2012 11:55:02 AM - Info dbclient(pid=880) INF - Using backup image SQ-ITU01-005.MSSQL7.LYNCSQLSRV\LYNCINSTANCE.trx.LE_AR.~.7.001of001.20120619115500..C
6/19/2012 11:55:02 AM - Info dbclient(pid=880) INF - backup log "LE_AR" to VIRTUAL_DEVICE='VNBU0-880-7188-1340096102' with stats = 10, blocksize = 65536, maxtransfersize = 4194304, buffercount = 2
6/19/2012 11:55:02 AM - Info dbclient(pid=880) INF - Number of stripes: 1, Number of buffers per stripe 2.
6/19/2012 11:55:02 AM - Info dbclient(pid=880) INF - Created VDI object for SQL Server instance <LYNCINSTANCE>. Connection timeout is <300> seconds.
6/19/2012 11:55:34 AM - Info dbclient(pid=880) INF - Thread has been closed for stripe #0
6/19/2012 11:55:43 AM - Info dbclient(pid=880) INF - OPERATION #7 of batch C:\Program Files\Veritas\NetBackup\DbExt\MsSql\LYNC_SQLSRV_LOGS.bch SUCCEEDED with STATUS 0 (0 is normal). Elapsed time = 43(1) seconds
6/19/2012 11:55:56 AM - Info dbclient(pid=880) INF - BACKUP STARTED USING
6/19/2012 11:55:56 AM - Info dbclient(pid=880) Microsoft SQL Server 2008 R2 (RTM) - 10.50.1600.1 (X64)
6/19/2012 11:55:56 AM - Info dbclient(pid=880) Apr 2 2010 15:48:46
6/19/2012 11:55:56 AM - Info dbclient(pid=880) Copyright (c) Microsoft Corporation
6/19/2012 11:55:56 AM - Info dbclient(pid=880) Enterprise Edition (64-bit) on Windows NT 6.1 <X64> (Build 7601: Service Pack 1)
6/19/2012 11:55:56 AM - Info dbclient(pid=880) Batch = C:\Program Files\Veritas\NetBackup\DbExt\MsSql\LYNC_SQLSRV_LOGS.bch, Op# = 8
6/19/2012 11:55:56 AM - Info dbclient(pid=880) INF - Using backup image SQ-ITU01-005.MSSQL7.LYNCSQLSRV\LYNCINSTANCE.trx.LE_EN.~.7.001of001.20120619115555..C
6/19/2012 11:55:56 AM - Info dbclient(pid=880) INF - backup log "LE_EN" to VIRTUAL_DEVICE='VNBU0-880-2292-1340096156' with stats = 10, blocksize = 65536, maxtransfersize = 4194304, buffercount = 2
6/19/2012 11:55:57 AM - Info dbclient(pid=880) INF - Number of stripes: 1, Number of buffers per stripe 2.
6/19/2012 11:55:57 AM - Info dbclient(pid=880) INF - Created VDI object for SQL Server instance <LYNCINSTANCE>. Connection timeout is <300> seconds.
6/19/2012 12:00:57 PM - Info dbclient(pid=880) ERR - Error in GetConfiguration: 0x80770003.
6/19/2012 12:00:57 PM - Info dbclient(pid=880)     CONTINUATION: - The api was waiting and the timeout interval had elapsed.
6/19/2012 12:01:02 PM - Info dbclient(pid=880) ERR - Error in VDS->Close: 0x80770004.
6/19/2012 12:01:02 PM - Info dbclient(pid=880)     CONTINUATION: - An abort request is preventing anything except termination actions.
6/19/2012 12:01:02 PM - Info dbclient(pid=880) INF - OPERATION #8 of batch C:\Program Files\Veritas\NetBackup\DbExt\MsSql\LYNC_SQLSRV_LOGS.bch FAILED with STATUS 1 (0 is normal). Elapsed time = 307(307) seconds.
6/19/2012 12:01:15 PM - Info dbclient(pid=880) INF - BACKUP STARTED USING
6/19/2012 12:01:15 PM - Info dbclient(pid=880) Microsoft SQL Server 2008 R2 (RTM) - 10.50.1600.1 (X64)
6/19/2012 12:01:15 PM - Info dbclient(pid=880) Apr 2 2010 15:48:46
6/19/2012 12:01:15 PM - Info dbclient(pid=880) Copyright (c) Microsoft Corporation
6/19/2012 12:01:15 PM - Info dbclient(pid=880) Enterprise Edition (64-bit) on Windows NT 6.1 <X64> (Build 7601: Service Pack 1)
6/19/2012 12:01:15 PM - Info dbclient(pid=880) Batch = C:\Program Files\Veritas\NetBackup\DbExt\MsSql\LYNC_SQLSRV_LOGS.bch, Op# = 9
6/19/2012 12:01:15 PM - Info dbclient(pid=880) INF - Using backup image SQ-ITU01-005.MSSQL7.LYNCSQLSRV\LYNCINSTANCE.trx.lis.~.7.001of001.20120619120114..C
6/19/2012 12:01:16 PM - Info dbclient(pid=880) INF - backup log "lis" to VIRTUAL_DEVICE='VNBU0-880-7004-1340096475' with stats = 10, blocksize = 65536, maxtransfersize = 4194304, buffercount = 2
6/19/2012 12:01:16 PM - Info dbclient(pid=880) INF - Number of stripes: 1, Number of buffers per stripe 2.
6/19/2012 12:01:16 PM - Info dbclient(pid=880) INF - Created VDI object for SQL Server instance <LYNCINSTANCE>. Connection timeout is <300> seconds.

Yogesh9881 · ‎06-19-2012

How long time taken to finish by Automatic & manual ?

Marianne · ‎06-19-2012

Could you please post dbclient log for successful and unsuccessful backup?

If successful and unsuccessful backup was done on the same day, please indicate what time each of them started and ended.

I would like to compare the two to see what is different.

We see a 5-minute timeout in the failed job:

/19/2012 11:36:40 AM - Info dbclient(pid=880) INF - Created VDI object for SQL Server instance <LYNCINSTANCE>. Connection timeout is <300> seconds.
6/19/2012 11:41:43 AM - Info dbclient(pid=880) ERR - Error in GetConfiguration: 0x80770003.
6/19/2012 11:41:43 AM - Info dbclient(pid=880) CONTINUATION: - The api was waiting and the timeout interval had elapsed.

Can you confirm that the same media server is used for both backups?

If different, please compare Client Connect Timeout and Client Read Timeout on the media servers.

Handy NetBackup Links

Saqib_Alam · ‎06-20-2012

Yes same media server.

Find the attached logs for sucessfull & unsucessful backup.

Marianne · ‎06-20-2012

The Attachments look like Activity Monitor job details,

Can we get dbclient log(s) for these backups?

Handy NetBackup Links

Saqib_Alam · ‎06-25-2012

SQLVDI: Loc=SignalAbort. Desc=Client initiates abort. ErrorCode=(0). Process=6924. Thread=8164. Client. Instance=LYNCINSTANCE. VD=Global\VNBU0-6924-8164-1340604894_SQLVDIMemoryName_0.

Can any one exlain what this.

Marianne · ‎06-25-2012

Please supply dbclient log(s) for successful manual backup and unsuccessful manual backup.

You need to ask SQL dba about SQLVDI errors.

PS:
Have a look at this featured post that will help you as Accredited Partner with troubleshooting and with providing sufficient information to Connect and/or Symantec Support when asking for assistance:

https://www-secure.symantec.com/connect/forums/netbackup-basics-and-how-make-your-life-easier

Handy NetBackup Links

VOX

Manual Backup of SQL