Solved: Error bprd (pid=23535) Unable to write progress lo...

luca72m · ‎11-19-2010

Hi,

I have a problem trying a Oracle Data Base backup. The error message the backup takes is:

19-nov-2010 11

- Error bprd (pid=23535) Unable to write progress log </C/ProgramÀ Files/Veritas/NetBackup/Logs/user_ops/dbext/logs/4804.0.1290163054> on client xxxxxxx Policy=xxx-xxx.xxx.xxx.xxxxxxxx Sched=xxx-xxx.xxx

I have checked all command to find problems whit the network but everything works fine. The master server and media server connect with the client correctly. The automatic scheduler starts the script and so the RMAN function :

connected to target database: ORCL (DBID=1023686199)
using target database controlfile instead of recovery catalog

RMAN>
old RMAN configuration parameters:
CONFIGURE CONTROLFILE AUTOBACKUP ON;
new RMAN configuration parameters:
CONFIGURE CONTROLFILE AUTOBACKUP ON;
new RMAN configuration parameters are successfully stored

RMAN> 2> 3> 4> 5> 6> 7> 8> 9> 10> 11> 12> 13> 14> 15> 16> 17> 18> 19> 20> 21> 22> 23> 24> 25> 26> 27> 28> 29> 30> 31> 32>
allocated channel: ch00
channel ch00: sid=12 devtype=SBT_TAPE
channel ch00: Veritas NetBackup for Oracle - Release 6.5 (20070723)

sent command to channel: ch00

Starting backup at 2010-11-19:12:03:03
channel ch00: starting incremental level 0 datafile backupset
channel ch00: specifying datafile(s) in backupset
including current controlfile in backupset
input datafile fno=00001 name=E:\ORADATA\ORCL\SYSTEM01.DBF
input datafile fno=00006 name=E:\ORADATA\ORCL\SYSTEM02.DBF
input datafile fno=00007 name=E:\ORADATA\ORCL\USERDATA01.DBF
input datafile fno=00008 name=E:\ORADATA\ORCL\INDEX01.DBF
input datafile fno=00009 name=E:\ORADATA\ORCL\UNDOTBS02.DBF
input datafile fno=00002 name=E:\ORADATA\ORCL\UNDOTBS01.DBF
input datafile fno=00003 name=E:\ORADATA\ORCL\INDX01.DBF
input datafile fno=00005 name=E:\ORADATA\ORCL\USERS01.DBF
input datafile fno=00004 name=E:\ORADATA\ORCL\TOOLS01.DBF
channel ch00: starting piece 1 at 2010-11-19:12:03:04

The policy uses full backup because oracle is a Standard edition and incremental is not supported.

For me netbackup finds a bad character (A) in folder </C/ProgramÀ Files/....

I've already checked the folder permissions on the logs folder and Everyone has full control. We are running netbackup client 6.5 and netbackup master server 7.0.1

thanks to all will help me.

luca72m · ‎11-24-2010

I solved the problem by updating the client to 6.5.5 version

Thanks to all !!!

View solution in original post

Andy_Welburn · ‎11-19-2010

i.e. is the Oracle client able to write to it?

Not sure about that bad character tho'?!

***EDIT***

Sorry, you already said that & confirmed same with your follow-up post!

luca72m · ‎11-19-2010

I think so ...

The folder ../log has this properties (Everyone full controll allow)

Andy_Welburn · ‎11-19-2010

with an error code?

Can you provide the output from the job details & or the full output that conatins the line you have already quoted?

Do you do 'normal' file system backups from this client? Do they work?

If you don't do these normally, can you try one?

***EDIT***

Going back to the logs, which one did you check the permissions for:

.../NetBackup/Logs/user_ops/dbext/logs...

or

.../NetBackup/Logs/user_ops/dbext/logs...

Marianne · ‎11-19-2010

I think you're onto something with "netbackup finds a bad character (A) in folder </C/ProgramÀ Files/...."

I remember seeing a TechNote... Will do some searching....

In the meantime, please confirm O/S on Master and Client.

Also create dbclient log on the client under netbackup\logs

Handy NetBackup Links

Andy_Welburn · ‎11-19-2010

Yeah, had a quick look too. Judy posted something a while back, so we might hear something from her later. Unfortunately, searching for "programA" resulted in a lot of Spanish results!!

luca72m · ‎11-19-2010

I have ready done a normal backup (file systems backup) and it works fine, skipping some file but work fine.

While the oracle backup doesn't work.

When automatic scheduler start execute the script and this is the output i see:

verifica schedulazioni
schedulazione utilizzata : sch-aut.day
utilizzo application backup = sch-app.day
==== started on ven 19/11/2010 13.24 ====
Script name: "C:\batch\hot_database_backup_ORCL.cmd"
#
RMAN : d:\oracle\ora92\bin\rman.exe
NLS_LANG : american
ORACLE_HOME : d:\oracle\ora92
ORACLE_SID : ORCL
NLS_DATE_FORMAT : YYYY-MM-DD:hh24:mi:ss
RMAN_LOG_FILE : "c:\batch\logs\rman_ORCL_19112010_13.24.00.log"
NB_ORA_SERV : michelangelo.unix.xxxxxxxxxxx.it
NB_ORA_FULL : 1
NB_ORA_INCR : 0
NB_ORA_CINC : 0
NB_ORA_CLASS : pol-vi1.igl.ora.xxxxxxx.orcl

Recovery Manager: Release 9.2.0.1.0 - Production

connected to target database: ORCL (DBID=1023686199)
using target database controlfile instead of recovery catalog

RMAN>
old RMAN configuration parameters:
CONFIGURE CONTROLFILE AUTOBACKUP ON;
new RMAN configuration parameters:
CONFIGURE CONTROLFILE AUTOBACKUP ON;
new RMAN configuration parameters are successfully stored

RMAN> 2> 3> 4> 5> 6> 7> 8> 9> 10> 11> 12> 13> 14> 15> 16> 17> 18> 19> 20> 21> 22> 23> 24> 25> 26> 27> 28> 29> 30> 31> 32>
allocated channel: ch00
channel ch00: sid=16 devtype=SBT_TAPE
channel ch00: Veritas NetBackup for Oracle - Release 6.5 (20070723)

sent command to channel: ch00

Starting backup at 2010-11-19:13:24:02
channel ch00: starting incremental level 0 datafile backupset
channel ch00: specifying datafile(s) in backupset
including current controlfile in backupset
input datafile fno=00001 name=E:\ORADATA\ORCL\SYSTEM01.DBF

input datafile fno=00006 name=E:\ORADATA\ORCL\SYSTEM02.DBF
input datafile fno=00007 name=E:\ORADATA\ORCL\USERDATA01.DBF
input datafile fno=00008 name=E:\ORADATA\ORCL\INDEX01.DBF
input datafile fno=00009 name=E:\ORADATA\ORCL\UNDOTBS02.DBF
input datafile fno=00002 name=E:\ORADATA\ORCL\UNDOTBS01.DBF
input datafile fno=00003 name=E:\ORADATA\ORCL\INDX01.DBF
input datafile fno=00005 name=E:\ORADATA\ORCL\USERS01.DBF
input datafile fno=00004 name=E:\ORADATA\ORCL\TOOLS01.DBF
channel ch00: starting piece 1 at 2010-11-19:13:24:03

channel ch00 at 13:24 call application backup and it stop abbnormaly whit status code 12 and write this error:

19-nov-2010 13.24.16 - Error bprd (pid=11677) Unable to write progress log </C/ProgramÀ Files/Veritas/NetBackup/Logs/user_ops/dbext/logs/5132.0.1290169444> on client amilora4. Policy=pol-vi1.igl.ora.amilora4.orcl Sched=sch-app.day
19-nov-2010 13.24.16 - Error bprd (pid=11677) CLIENT amilora4 POLICY pol-vi1.igl.ora.amilora4.orcl SCHED sch-app.day EXIT STATUS 12 (file open failed)

Then the automatic scheduler end with status 29 and write this error:

1: (29) failed trying to exec a command

If you wont I can send the script file even if the error is not in the script.

I use this model of the script for many other client in my architeture systems.

Thanks a lot ...

Marianne · ‎11-19-2010

The process flow works a bit different when it's a database backup. Comms is initiated by the client and bprd on the master needs to update progress logs on the client.

I found this post: https://www-secure.symantec.com/connect/forums/netbackup-655-status-code-12 :

"Fixed;

We created a new local admin account, ran the service under that and, for good measure, gave rights to the new admin account on the Logs folder "

Question - does the oracle user account have local admin rights? If so, change the NetBackup Client Service logon account to the oracle user.

Handy NetBackup Links

luca72m · ‎11-19-2010

O/S is windows 2000

client netbackup is 6.5

master server is 7.0.1

I have unistall netbackup client to client whit control pannel Add/remove Programs and install the client in a new directory c:\Veritas\NetBackup but netbackup create just the same the directory C:\Program Files\VERITAS\NetBackup mabye because the register has old keys... and it uses the default dir for register log.

this is the log in the dbclient file:

14:12:21.430 [1376.5296] <8> xbsa_GetEnv: WRN - NBBSA_SCHEDULE not found in environment block

14:12:21.430 [1376.5296] <2> int_LogSystemInfo: INF -
Veritas NetBackup for Oracle - Release 6.5 (20070723)
  Platform:  Windows
  Computer:  amilora4
  OS Release:  5.0
  Build number: 2195
  Misc Info:  Service Pack 4
  User:   SYSTEM
  Client Host: amilora4

14:12:21.430 [1376.5296] <2> int_GetMMInfo: INF - support for Proxy Copy enabled
14:12:21.633 [1376.5296] <4> int_ProcessCommandString: INF - cmd_key=<NB_ORA_SCHED> cmd_val=<sch-app.day>
14:12:21.633 [1376.5296] <2> int_ProcessCommand: INF - Schedule <sch-app.day> will be used for non-proxy API session.
14:12:21.633 [1376.5296] <4> int_ProcessCommandString: INF - cmd_key=<NB_ORA_CLIENT> cmd_val=<amilora4>
14:12:21.633 [1376.5296] <2> int_ProcessCommand: INF - Client <amilora4> will be used for this API session.
14:12:21.633 [1376.5296] <4> int_ProcessCommandString: INF - cmd_key=<NB_ORA_POLICY> cmd_val=<pol-vi1.igl.ora.amilora4.orcl>
14:12:21.633 [1376.5296] <2> int_ProcessCommand: INF - Policy <pol-vi1.igl.ora.amilora4.orcl> will be used for this API session.
14:12:23.242 [1376.5296] <2> vnet_vnetd_service_socket: ..\libvlibs\vnet_vnetd.c.2043: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006
14:12:23.242 [1376.5296] <2> vnet_vnetd_service_socket: ..\libvlibs\vnet_vnetd.c.2057: service: bprd
14:12:23.242 [1376.5296] <2> logconnections: BPRD CONNECT FROM 192.168.147.168.1561 TO 192.168.147.137.13724
14:12:23.352 [1376.5296] <2> xbsa_ProgressLogSetup: INF - No progress log: Job will proceed without progress logging
14:12:23.367 [1376.5296] <2> vnet_vnetd_service_socket: ..\libvlibs\vnet_vnetd.c.2043: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006
14:12:23.367 [1376.5296] <2> vnet_vnetd_service_socket: ..\libvlibs\vnet_vnetd.c.2057: service: bprd
14:12:23.383 [1376.5296] <2> logconnections: BPRD CONNECT FROM 192.168.147.168.1562 TO 192.168.147.137.13724

luca72m · ‎11-19-2010

sorry missing the final part of output log

14:27:28.471 [1376.5296] <16> readCommFile: ERR - timed out after 900 seconds while reading from C:\Program Files\Veritas\NetBackup\Logs\user_ops\dbext\logs\5296.0.1290172343
14:27:28.471 [1376.5296] <32> serverResponse: ERR - could not read from comm file <C:\Program Files\Veritas\NetBackup\Logs\user_ops\dbext\logs\5296.0.1290172343>
14:27:28.471 [1376.5296] <16> CreateNewImage: ERR - serverResponse() failed
14:27:28.471 [1376.5296] <16> VxBSACreateObject: ERR - Could not create new image with file /bk-hot-ORCL-l0-ch3-19112010_14.12.19-Unnltd9u5_1_1-s7927.
14:27:28.471 [1376.5296] <16> xbsa_CreateObject: ERR - VxBSACreateObject: Failed with error:
Server Status: Communication with the server has not been iniatated or the server status has not been retrieved from the serve
14:27:39.064 [1376.5296] <4> sbtend: INF - --- END of SESSION ---
14:27:39.064 [1376.5296] <8> close_image: Session being terminated abnormally, cleaning up
14:27:39.064 [1376.5296] <4> close_image: INF - backup FAILED
14:27:39.064 [1376.5296] <4> close_image: INF ---- end of Backup ---

luca72m · ‎11-19-2010

The netbackup service already logon with oracle user and this has already admin right ...

luca72m · ‎11-24-2010

I solved the problem by updating the client to 6.5.5 version

Thanks to all !!!

Andy_Welburn · ‎11-24-2010

Glad you managed to get it resolved - funny how an upgrade or a re-install manages to fix some issues!

Have marked your post as the solution.

VOX

Error bprd (pid=23535) Unable to write progress log </C/ProgramÀ Files/Veritas/NetBackup/Logs/user_ops/dbext/logs/....