cancel
Showing results for 
Search instead for 
Did you mean: 

Encryption Backup fails with Status Code 10

Maximiliano_Rad
Level 3

Hi all.

I´m trying Encryption option on my Netbackup environment.

I need to encrypt data on tapes.

I followed these steps before check the Encryption Option on a test policy

https://www.veritas.com/support/en_US/article.TECH67972

Also I configured the client to allow encryption in AES-256

Encri.pngEncri2.png

I created a test policy which backs up a small file on the client, this policy points directly to a volume pool which contains only a single tape.

When I run this policy, it ends with status code 10

 

here the details.

4/7/2016 12:39:31 PM - requesting resource moreliabak01-hcart2-robot-tld-0
4/7/2016 12:39:31 PM - requesting resource moreliabak01.morelia.corporativo.posadas.com.mx.NBU_CLIENT.MAXJOBS.moreliabak01
4/7/2016 12:39:31 PM - requesting resource moreliabak01.morelia.corporativo.posadas.com.mx.NBU_POLICY.MAXJOBS.test-encr
4/7/2016 12:39:31 PM - granted resource moreliabak01.morelia.corporativo.posadas.com.mx.NBU_CLIENT.MAXJOBS.moreliabak01
4/7/2016 12:39:31 PM - granted resource moreliabak01.morelia.corporativo.posadas.com.mx.NBU_POLICY.MAXJOBS.test-encr
4/7/2016 12:39:31 PM - granted resource 0042L6
4/7/2016 12:39:31 PM - granted resource IBM.ULT3580-HH6.001
4/7/2016 12:39:31 PM - granted resource moreliabak01-hcart2-robot-tld-0
4/7/2016 12:39:31 PM - estimated 0 Kbytes needed
4/7/2016 12:39:31 PM - Info nbjm(pid=5880) started backup job for client moreliabak01, policy test-encr, schedule Full on storage unit moreliabak01-hcart2-robot-tld-0
4/7/2016 12:39:31 PM - started process bpbrm (18920)
4/7/2016 12:39:31 PM - connecting
4/7/2016 12:39:31 PM - connected; connect time: 00:00:00
4/7/2016 12:39:32 PM - mounting 0042L6
4/7/2016 12:39:44 PM - Error bpbrm(pid=18920) could not send server status message       
4/7/2016 12:39:44 PM - end writing
allocation failed(10)
4/7/2016 12:39:49 PM - Info bpbkar32(pid=20296) done. status: 10: allocation failed     

I tested the resources utilization but it seems to be OK

I tried Marianne troubleshoot by creating an empty file named NOSHM on ...\veritas\netbackup

But it didn´t work. 

I´m using NBU 7.1.0.3 on a Windows server 2008.

I´m testing this policy using the master server as a client and also a remote windows server with same resuts.

Could you help me please?

Thanks.

Maximiliano

4 REPLIES 4

Michael_G_Ander
Level 6
Certified

Status 10 is often related to some kind of memory exhaustion, here you are probably hitting one the limits on the internal memory stuff like non-interactive desktop heap.

Is there an eventid 26 in the event log ?  Then this technote could be relevant: https://www.veritas.com/support/en_US/article.TECH48099

Does the bpbkar log show any warnings/errors ?

Another thing I have seen giving errors is small page files like, on for example 500 MB, as some windows things seem to use the page file if it exists

BTW you should consider upgrading as 7.1 went end of life quite some time ago.

The standard questions: Have you checked: 1) What has changed. 2) The manual 3) If there are any tech notes or VOX posts regarding the issue

Maximiliano_Rad
Level 3

HI Michael

I have System EventID´s 26 But they´re older than my first attemp of running a Encrypted Backup.

BPKAR logs showed this information

 


12:06:54.232 PM: [4968.6848] <4> ov_log::OVInit: INF - Starting log file: D:\Program Files\Veritas\NetBackup\logs\BPBKAR\040816.LOG

12:06:54.232 PM: [4968.6848] <4> ov_log::OVInit: GENERAL Log Level: 0
12:06:54.232 PM: [4968.6848] <4> ov_log::OVInit: TCP Log Level: 0
12:06:54.232 PM: [4968.6848] <4> ov_log::OVInit: INF - the log mutex: 532
BPBKAR  NetBackup Backup/Archive  7.1GA  [Dec  7 2011]
Copyright © 1993 - 2011 Symantec Corporation, All Rights Reserved.
All Rights Reserved.

12:06:54.232 PM: [4968.6848] <2> WinMain: DAT - _pgmptr = 'D:\Program Files\Veritas\NetBackup\bin\bpbkar32.exe'
12:06:54.232 PM: [4968.6848] <2> WinMain: DAT - lpCmdLine = '-r 1209600 -ru root -dt 0 -to 0 -clnt posmexbak01 -class test-encr -sched Full -st FULL -bpstart_to 300 -bpend_to 300 -read_to 300 -blks_per_buffer 512 -use_otm -fso -F encipher -b posmexbak01_1460135213 -kl 32 -WOFB_enabled -WOFB_fim 0 -WOFB_usage 0 -WOFB_error 0 -ct 13 -use_ofb '
12:06:54.232 PM: [4968.6848] <2> date_debug: DAT - timezone: Central Standard Time (Mexico), offset=21600, dst: Central Daylight Time (Mexico)
12:06:54.232 PM: [4968.6848] <2> date_debug: DAT - current time: 1460135214, 4/8/2016 12:06:54 PM
12:06:54.232 PM: [4968.6848] <2> date_debug: DAT - 01/01/94 UCT:  757382400, 12/31/1993 7:00:00 PM
12:06:54.232 PM: [4968.6848] <2> date_debug: DAT - 07/01/94 UCT:  773020800, 6/30/1994 7:00:00 PM
12:06:54.232 PM: [4968.6848] <2> WinMain: DAT - standard input handle = 432
12:06:54.232 PM: [4968.6848] <2> WinMain: DAT - standard output handle = 284
12:06:54.232 PM: [4968.6848] <2> WinMain: DAT - standard error handle = 464
12:06:54.310 PM: [4968.6848] <4> tar_backup::V_SetupJobData: INF - dwJobData: ffffffff
12:06:54.310 PM: [4968.6848] <4> tar_backup::V_SetupJobData: INF -     dwJob: ffffffff
12:06:54.341 PM: [4968.6848] <4> dos_backup::V_PreProcessing: INF - backup privileges enabled, previous = 0
12:06:54.341 PM: [4968.6848] <4> dos_backup::V_PreProcessing: INF - restore privileges enabled, previous = 0
12:06:54.341 PM: [4968.6848] <4> dos_backup::V_PreProcessing: INF - security privileges enabled, previous = 0
12:06:54.341 PM: [4968.6848] <4> dos_backup::V_PreProcessing: INF - tcb privileges enabled, previous = 0
12:06:54.341 PM: [4968.6848] <4> dos_backup::V_PreProcessing: INF - create token privileges enabled, previous = 0
12:06:54.341 PM: [4968.6848] <4> dos_backup::V_PreProcessing: INF - user name: root
12:06:54.341 PM: [4968.6848] <4> dos_backup::V_PreProcessing: INF - no access token: scheduled backup
12:06:54.341 PM: [4968.6848] <2> ov_log::V_GlobalLog: WRN - ubsDetermineExchangeVersion(): RegOpenKeyEx() failed for HKLM\SOFTWARE\MICROSOFT\ExchangeServer\v14\Setup - 0x2.
12:06:54.341 PM: [4968.6848] <2> ov_log::V_GlobalLog: INF - BEDS_Init() Enter InitFlags:0x0
12:06:54.403 PM: [4968.6848] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: C:
12:06:54.403 PM: [4968.6848] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: D:
12:06:54.403 PM: [4968.6848] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Microsoft Terminal Services
12:06:54.403 PM: [4968.6848] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Microsoft Windows Network
12:06:54.403 PM: [4968.6848] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Shadow?Copy?Components
12:06:54.403 PM: [4968.6848] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: System?State
12:06:54.403 PM: [4968.6848] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Active Directory Application Mode
12:06:54.403 PM: [4968.6848] <2> tar_base::V_vTarMsgW: INF - BACKUP START 4968
12:06:54.403 PM: [4968.6848] <2> tar_backup::V_SetupProcessContinue: TAR - CONTINUE BACKUP received
12:06:54.403 PM: [4968.6848] <2> tar_base::V_vTarMsgW: INF - CONTINUE BACKUP message received
12:06:54.403 PM: [4968.6848] <2> tar_backup_tfi::setupFileDirectives: TAR - backup filename = C:\Report
12:06:54.403 PM: [4968.2000] <4> tar_base::keepaliveThread: INF - keepalive thread is active with an interval of 60 seconds
12:06:54.481 PM: [4968.6848] <2> tar_backup_vxbsa::add: INF - called with 'C:\Report'
12:06:54.497 PM: [4968.6848] <4> dos_backup::V_VerifyFileList: INF - UBS Local Type for 'C:\Report' --> 10020002
12:07:04.871 PM: [4968.6848] <16> tar_tfi::createTransport: ERR - Packer creation exception.
12:07:04.871 PM: [4968.6848] <2> tar_base::V_vTarMsgW: INF - EXIT STATUS 10: allocation failed
12:07:04.871 PM: [4968.6848] <4> backup_create: INF - NetBackup Temp Directory: 'D:\Program Files\Veritas\\NetBackup\Temp'
12:07:04.871 PM: [4968.6848] <16> tar_backup::SetExcludeFileList: **** DBG **** - alternate client fileter file is null
12:07:04.871 PM: [4968.6848] <4> tar_backup_tfi::UpdateExcludeListWithVHD: INF - UpdateExludeListWithVHD begin
12:07:04.934 PM: [4968.6848] <4> tar_backup::backup_done_state: INF - number of file directives not found: 0
12:07:04.934 PM: [4968.6848] <4> tar_backup::backup_done_state: INF -     number of file directives found: 0
12:07:04.934 PM: [4968.2000] <4> tar_base::keepaliveThread: INF - keepalive thread terminating (reason: WAIT_OBJECT_0)
12:07:04.934 PM: [4968.6848] <4> tar_base::stopKeepaliveThread: INF - keepalive thread has exited. (reason: WAIT_OBJECT_0)
12:07:04.934 PM: [4968.6848] <2> tar_base::V_vTarMsgW: INF - EXIT STATUS 10: allocation failed
12:07:04.934 PM: [4968.6848] <4> tar_backup::backup_done_state: INF - Not waiting for server status
12:07:04.934 PM: [4968.6848] <4> dos_backup::tfs_reset: INF - Snapshot deletion start
12:07:04.934 PM: [4968.6848] <4> OVStopCmd: INF - EXIT - status = 0
12:07:04.934 PM: [4968.6848] <2> tar_base::V_Close: closing...
12:07:04.934 PM: [4968.6848] <4> dos_backup::tfs_reset: INF - Snapshot deletion start
12:07:04.934 PM: [4968.6848] <2> ov_log::V_GlobalLog: INF - BEDS_Term(): enter - InitFlags:0x00000101
12:07:04.934 PM: [4968.6848] <2> ov_log::V_GlobalLog: INF - BEDS_Term(): ubs specifics: 0x001d0000
12:07:04.949 PM: [4968.6848] <16> dtcp_read: TCP - failure: recv socket (432) (TCP 10053: Software caused connection abort)
12:07:05.963 PM: [4968.6848] <16> dtcp_read: TCP - failure: recv socket (432) (TCP 10053: Software caused connection abort)
12:07:06.977 PM: [4968.6848] <16> dtcp_read: TCP - failure: recv socket (432) (TCP 10053: Software caused connection abort)
12:07:07.991 PM: [4968.6848] <16> dtcp_read: TCP - failure: recv socket (432) (TCP 10053: Software caused connection abort)
12:07:09.005 PM: [4968.6848] <16> dtcp_read: TCP - failure: recv socket (432) (TCP 10053: Software caused connection abort)
12:07:10.019 PM: [4968.6848] <16> dtcp_read: TCP - failure: recv socket (432) (TCP 10053: Software caused connection abort)
12:07:11.033 PM: [4968.6848] <16> dtcp_read: TCP - failure: recv socket (432) (TCP 10053: Software caused connection abort)
12:07:11.033 PM: [4968.6848] <4> OVShutdown: INF - Finished process
12:07:11.033 PM: [4968.6848] <4> WinMain: INF - Exiting D:\Program Files\Veritas\NetBackup\bin\bpbkar32.exe
12:07:13.061 PM: [4968.6848] <4> ov_log::OVClose: INF - Closing log file: D:\Program Files\Veritas\NetBackup\logs\BPBKAR\040816.LOG
 

The page files is se tto 8146 MB

 

page file.png

We are about to Upgrade Netbackup. 

BTW is needed a special licence to encrypt data?

Thanks 
 

Max

Will_Restore
Level 6

BTW is needed a special licence to encrypt data?

 

To confirm that NetBackup encryption is registered on a Windows NetBackup master server
◆ Log on as an Administrator. Use the Help > License Keys menu in the NetBackup Administration Console to list and add keys.

 

You should see

Encryption (Legacy DES 40-bit)

Encryption (Legacy DES 56-bit)

 

 

Maximiliano_Rad
Level 3

I found the error.


I didn´t have configured the client password.

After running that command

<install_path>\NetBackup\bin\bpkeyutil -client <client name>

backups worked fine.

Thanks

Max