Backup Oracle with NB fails with a lot of errors

[Hristo]

Hi!

Im trying to figure out what is wrong with this backup. We are using Oracle 10204 and Netbackup 6.5.1. Below is the message.

[code]

INF - channel ch00: reading from backup piece bk_urjk6atah_s3955_p1_t677737809
INF - ORA-19870: error reading backup piece bk_urjk6atah_s3955_p1_t677737809
INF - ORA-19507: failed to retrieve sequential file, handle="bk_urjk6atah_s3955_p1_t677737809", parms=""
INF - ORA-27029: skgfrtrv: sbtrestore returned error
INF - ORA-19511: Error received from media manager layer, error text:
INF -    Backup file <bk_urjk6atah_s3955_p1_t677737809> not found in NetBackup catalog

[/code]

This have never worked in the past. Im really new to this so please have patience. Where do I begin?

Regards

H

[Amit_Karia]

1) Make sure your RMAN script has execute persmission

2) please check dbclient and bpdhb logs.. if possible send them accross

3) Have you installed oracle agent for netbackup to the same level as master server

4) Let us know if Policy is not generating any streams or parent stream is failing with 6

5) Make sure you have NB_ORA_CLIENT,NB_ORA_SERVER and NB_ORA_POLICY defined in Rman script

after allocating channels

[Amit_Karia]

Also make sure client_name in bp.conf and client name in policy are exactly same(case sensitive)

[Hristo]

Hi!

Thanks for your fast response. I will look into that.

We are running Win 2003 server x64. dont know if that makes any difference?

Im trying to make a RESTORE.  

Regards

H

Message Edited by Hristo on 02-04-2009 05:35 AM

[Nicolai]

I think you need to run a RMAN crosscheck command so the RMAN recover catalog get updated with what Netbackup has. It seem RMAN is trying to restore a expired IMAGE.

Or you got the bp.conf wrong as mentioned in this thread.

Regards

Nicolai

[Hristo]

Hi!

We are not using RMAN catalog.

Isnt bp.conf only on UNIX/Linux?

Regards

H

[sdo]

When NBU'ers say bp.conf we mean the "NetBackup config" in general.  As you know a Windows client has no bp.conf.  All client config items are stored in the registry, so when we say check the client name in bp.conf for a Windows client, we do of course mean you to use:

1) Regedit (if you know where to look).

2) The client GUI (if you know where to look).

3) Or my fav way with a Windows client, this command from a "server" of the client (I usually run this on the master - but a media server will normally do too):

$ bpgetconfig -M $server CLIENT_NAME

HTH.

[marekkedzierski]

1. restore controlfile

2. mount database

3. restore database

4. recover database

5. open database

[Hristo]

Have you even read the posts above you?

Regards

H

[Hristo]

This is from the bpdhb:

23:00:01.833 [33324.34476] <2> logparams: -sb -rdbms oracle -S sestows140 -to 300 -c sestows140_oracle_sestons01_db02 -s Full -clnt sestons02 -FULL -kl 28 -b sestons02_1233612000 -jobid 172931 23:00:01.833 [33324.34476] <4> bphdb: INF - setenv NB_ORA_POLICY=sestows140_oracle_sestons01_db0223:00:01.833 [33324.34476] <4> bphdb: INF - setenv NB_ORA_CLIENT=sestons0223:00:01.833 [33324.34476] <4> bphdb: INF - setenv NB_ORA_MODE=B23:00:01.833 [33324.34476] <4> bphdb: INF - NB_ORA_POLICY=sestows140_oracle_sestons01_db0223:00:01.833 [33324.34476] <4> bphdb: INF - NB_ORA_SCHED not defined.23:00:01.833 [33324.34476] <4> bphdb: INF - NB_ORA_PC_SCHED=Full23:00:01.833 [33324.34476] <4> bphdb: INF - NB_ORA_SERV=sestows14023:00:01.833 [33324.34476] <4> bphdb: INF - NB_ORA_PC_BTYPE not set23:00:01.833 [33324.34476] <4> bphdb: INF - setenv NB_ORA_FULL=123:00:01.833 [33324.34476] <4> bphdb: INF - setenv NB_ORA_INCR=023:00:01.833 [33324.34476] <4> bphdb: INF - setenv NB_ORA_CINC=023:00:01.833 [33324.34476] <4> bphdb: INF - setenv NB_ORA_SCHEDULED=123:00:01.833 [33324.34476] <4> bphdb: INF - BACKUP START23:00:01.911 [33324.34476] <4> bphdb: INF - CONTINUE BACKUP message received.23:00:01.911 [33324.34476] <4> bphdb: INF - Processing "C:\Program Files\VERITAS\NetBackup\DbExt\Oracle\hot_db_backup_db02.cmd"23:00:03.364 [30292.33380] <2> logparams: -sb -rdbms oracle -S sestows140 -to 300 -c sestows140_oracle_sestons01_db01 -s Full -clnt sestons02 -FULL -kl 28 -b sestons02_1233612001 -jobid 172932 23:00:03.364 [30292.33380] <4> bphdb: INF - setenv NB_ORA_POLICY=sestows140_oracle_sestons01_db0123:00:03.364 [30292.33380] <4> bphdb: INF - setenv NB_ORA_CLIENT=sestons0223:00:03.364 [30292.33380] <4> bphdb: INF - setenv NB_ORA_MODE=B23:00:03.364 [30292.33380] <4> bphdb: INF - NB_ORA_POLICY=sestows140_oracle_sestons01_db0123:00:03.364 [30292.33380] <4> bphdb: INF - NB_ORA_SCHED not defined.23:00:03.364 [30292.33380] <4> bphdb: INF - NB_ORA_PC_SCHED=Full23:00:03.364 [30292.33380] <4> bphdb: INF - NB_ORA_SERV=sestows14023:00:03.364 [30292.33380] <4> bphdb: INF - NB_ORA_PC_BTYPE not set23:00:03.364 [30292.33380] <4> bphdb: INF - setenv NB_ORA_FULL=123:00:03.364 [30292.33380] <4> bphdb: INF - setenv NB_ORA_INCR=023:00:03.364 [30292.33380] <4> bphdb: INF - setenv NB_ORA_CINC=023:00:03.364 [30292.33380] <4> bphdb: INF - setenv NB_ORA_SCHEDULED=123:00:03.364 [30292.33380] <4> bphdb: INF - BACKUP START23:00:03.364 [30292.33380] <4> bphdb: INF - CONTINUE BACKUP message received.23:00:03.364 [30292.33380] <4> bphdb: INF - Processing "C:\Program Files\VERITAS\NetBackup\DbExt\Oracle\hot_db_backup_db01.cmd"23:01:02.004 [33324.34476] <4> bphdb: INF - bphdb still working.23:01:03.395 [30292.33380] <4> bphdb: INF - bphdb still working.23:02:02.004 [33324.34476] <4> bphdb: INF - bphdb still working.23:02:03.395 [30292.33380] <4> bphdb: INF - bphdb still working.23:03:02.004 [33324.34476] <4> bphdb: INF - bphdb still working.23:03:03.394 [30292.33380] <4> bphdb: INF - bphdb still working.23:04:02.003 [33324.34476] <4> bphdb: INF - bphdb still working.23:04:03.394 [30292.33380] <4> bphdb: INF - bphdb still working.23:05:02.004 [33324.34476] <4> bphdb: INF - bphdb still working.23:05:03.394 [30292.33380] <4> bphdb: INF - bphdb still working.23:06:02.004 [33324.34476] <4> bphdb: INF - bphdb still working.23:06:03.395 [30292.33380] <4> bphdb: INF - bphdb still working.23:07:02.004 [33324.34476] <4> bphdb: INF - bphdb still working.23:07:03.395 [30292.33380] <4> bphdb: INF - bphdb still working.23:08:02.005 [33324.34476] <4> bphdb: INF - bphdb still working.23:08:03.395 [30292.33380] <4> bphdb: INF - bphdb still working.23:09:02.005 [33324.34476] <4> bphdb: INF - bphdb still working.23:09:03.396 [30292.33380] <4> bphdb: INF - bphdb still working.23:10:02.005 [33324.34476] <4> bphdb: INF - bphdb still working.23:10:03.396 [30292.33380] <4> bphdb: INF - bphdb still working.23:11:02.006 [33324.34476] <4> bphdb: INF - bphdb still working.23:11:03.396 [30292.33380] <4> bphdb: INF - bphdb still working.23:11:56.444 [30292.33380] <4> bphdb: INF - command successfully initiated23:11:56.444 [30292.33380] <4> bphdb: INF - bphdb exit normal23:11:56.444 [30292.33380] <4> bphdb: INF - EXIT STATUS 0: the requested operation was successfully completed23:12:02.006 [33324.34476] <4> bphdb: INF - bphdb still working.23:12:21.241 [33324.34476] <4> bphdb: INF - command successfully initiated23:12:21.241 [33324.34476] <4> bphdb: INF - bphdb exit normal23:12:21.241 [33324.34476] <4> bphdb: INF - EXIT STATUS 0: the requested operation was successfully completed23:15:01.976 [35976.30424] <2> logparams: -sb -rdbms oracle -S sestows140 -to 300 -c sestows140_oracle_sestons01_db03 -s Full -clnt sestons02 -FULL -kl 28 -b sestons02_1233612900 -jobid 172946 23:15:01.992 [35976.30424] <4> bphdb: INF - setenv NB_ORA_POLICY=sestows140_oracle_sestons01_db0323:15:01.992 [35976.30424] <4> bphdb: INF - setenv NB_ORA_CLIENT=sestons0223:15:01.992 [35976.30424] <4> bphdb: INF - setenv NB_ORA_MODE=B23:15:01.992 [35976.30424] <4> bphdb: INF - NB_ORA_POLICY=sestows140_oracle_sestons01_db0323:15:01.992 [35976.30424] <4> bphdb: INF - NB_ORA_SCHED not defined.23:15:01.992 [35976.30424] <4> bphdb: INF - NB_ORA_PC_SCHED=Full23:15:01.992 [35976.30424] <4> bphdb: INF - NB_ORA_SERV=sestows14023:15:01.992 [35976.30424] <4> bphdb: INF - NB_ORA_PC_BTYPE not set23:15:01.992 [35976.30424] <4> bphdb: INF - setenv NB_ORA_FULL=123:15:01.992 [35976.30424] <4> bphdb: INF - setenv NB_ORA_INCR=023:15:01.992 [35976.30424] <4> bphdb: INF - setenv NB_ORA_CINC=023:15:01.992 [35976.30424] <4> bphdb: INF - setenv NB_ORA_SCHEDULED=123:15:01.992 [35976.30424] <4> bphdb: INF - BACKUP START23:15:02.070 [35976.30424] <4> bphdb: INF - CONTINUE BACKUP message received.23:15:02.070 [35976.30424] <4> bphdb: INF - Processing "C:\Program Files\VERITAS\NetBackup\DbExt\Oracle\hot_db_backup_db03.cmd"23:16:02.149 [35976.30424] <4> bphdb: INF - bphdb still working.23:17:02.150 [35976.30424] <4> bphdb: INF - bphdb still working.23:18:02.151 [35976.30424] <4> bphdb: INF - bphdb still working.23:19:02.151 [35976.30424] <4> bphdb: INF - bphdb still working.23:20:02.152 [35976.30424] <4> bphdb: INF - bphdb still working.23:21:02.153 [35976.30424] <4> bphdb: INF - bphdb still working.23:22:02.154 [35976.30424] <4> bphdb: INF - bphdb still working.23:23:02.155 [35976.30424] <4> bphdb: INF - bphdb still working.23:24:02.155 [35976.30424] <4> bphdb: INF - bphdb still working.23:25:02.156 [35976.30424] <4> bphdb: INF - bphdb still working.23:26:02.157 [35976.30424] <4> bphdb: INF - bphdb still working.23:27:02.158 [35976.30424] <4> bphdb: INF - bphdb still working.23:28:02.158 [35976.30424] <4> bphdb: INF - bphdb still working.23:29:02.159 [35976.30424] <4> bphdb: INF - bphdb still working.23:30:02.160 [35976.30424] <4> bphdb: INF - bphdb still working.23:30:17.926 [29924.36504] <2> logparams: -sb -rdbms oracle -S sestows140 -to 300 -c sestows140_oracle_sestons01_db04 -s Full -clnt sestons02 -FULL -kl 28 -b sestons02_1233613815 -jobid 172954 23:30:17.926 [29924.36504] <4> bphdb: INF - setenv NB_ORA_POLICY=sestows140_oracle_sestons01_db0423:30:17.926 [29924.36504] <4> bphdb: INF - setenv NB_ORA_CLIENT=sestons0223:30:17.926 [29924.36504] <4> bphdb: INF - setenv NB_ORA_MODE=B23:30:17.926 [29924.36504] <4> bphdb: INF - NB_ORA_POLICY=sestows140_oracle_sestons01_db0423:30:17.926 [29924.36504] <4> bphdb: INF - NB_ORA_SCHED not defined.23:30:17.926 [29924.36504] <4> bphdb: INF - NB_ORA_PC_SCHED=Full23:30:17.926 [29924.36504] <4> bphdb: INF - NB_ORA_SERV=sestows14023:30:17.926 [29924.36504] <4> bphdb: INF - NB_ORA_PC_BTYPE not set23:30:17.926 [29924.36504] <4> bphdb: INF - setenv NB_ORA_FULL=123:30:17.926 [29924.36504] <4> bphdb: INF - setenv NB_ORA_INCR=023:30:17.926 [29924.36504] <4> bphdb: INF - setenv NB_ORA_CINC=023:30:17.926 [29924.36504] <4> bphdb: INF - setenv NB_ORA_SCHEDULED=123:30:17.926 [29924.36504] <4> bphdb: INF - BACKUP START23:30:17.988 [29924.36504] <4> bphdb: INF - CONTINUE BACKUP message received.23:30:17.988 [29924.36504] <4> bphdb: INF - Processing "C:\Program Files\VERITAS\NetBackup\DbExt\Oracle\hot_db_backup_db04.cmd"23:31:02.161 [35976.30424] <4> bphdb: INF - bphdb still working.23:31:18.020 [29924.36504] <4> bphdb: INF - bphdb still working.23:31:34.567 [35976.30424] <4> bphdb: INF - command successfully initiated23:31:34.567 [35976.30424] <4> bphdb: INF - bphdb exit normal23:31:34.567 [35976.30424] <4> bphdb: INF - EXIT STATUS 0: the requested operation was successfully completed23:32:18.021 [29924.36504] <4> bphdb: INF - bphdb still working.23:33:18.022 [29924.36504] <4> bphdb: INF - bphdb still working.23:34:18.023 [29924.36504] <4> bphdb: INF - bphdb still working.23:35:18.023 [29924.36504] <4> bphdb: INF - bphdb still working.23:36:18.024 [29924.36504] <4> bphdb: INF - bphdb still working.23:37:18.025 [29924.36504] <4> bphdb: INF - bphdb still working.23:38:18.026 [29924.36504] <4> bphdb: INF - bphdb still working.23:39:18.026 [29924.36504] <4> bphdb: INF - bphdb still working.23:40:18.027 [29924.36504] <4> bphdb: INF - bphdb still working.23:41:18.028 [29924.36504] <4> bphdb: INF - bphdb still working.23:42:06.482 [29924.36504] <4> bphdb: INF - command successfully initiated23:42:06.482 [29924.36504] <4> bphdb: INF - bphdb exit normal23:42:06.482 [29924.36504] <4> bphdb: INF - EXIT STATUS 0: the requested operation was successfully completed23:45:02.328 [33620.34688] <2> logparams: -sb -rdbms oracle -S sestows140 -to 300 -c sestows140_oracle_sestons01_db05 -s Full -clnt sestons02 -FULL -kl 28 -b sestons02_1233614700 -jobid 172962 23:45:02.328 [33620.34688] <4> bphdb: INF - setenv NB_ORA_POLICY=sestows140_oracle_sestons01_db0523:45:02.328 [33620.34688] <4> bphdb: INF - setenv NB_ORA_CLIENT=sestons0223:45:02.328 [33620.34688] <4> bphdb: INF - setenv NB_ORA_MODE=B23:45:02.328 [33620.34688] <4> bphdb: INF - NB_ORA_POLICY=sestows140_oracle_sestons01_db0523:45:02.328 [33620.34688] <4> bphdb: INF - NB_ORA_SCHED not defined.23:45:02.328 [33620.34688] <4> bphdb: INF - NB_ORA_PC_SCHED=Full23:45:02.328 [33620.34688] <4> bphdb: INF - NB_ORA_SERV=sestows14023:45:02.328 [33620.34688] <4> bphdb: INF - NB_ORA_PC_BTYPE not set23:45:02.328 [33620.34688] <4> bphdb: INF - setenv NB_ORA_FULL=123:45:02.328 [33620.34688] <4> bphdb: INF - setenv NB_ORA_INCR=023:45:02.328 [33620.34688] <4> bphdb: INF - setenv NB_ORA_CINC=023:45:02.328 [33620.34688] <4> bphdb: INF - setenv NB_ORA_SCHEDULED=123:45:02.328 [33620.34688] <4> bphdb: INF - BACKUP START23:45:02.406 [33620.34688] <4> bphdb: INF - CONTINUE BACKUP message received.23:45:02.421 [33620.34688] <4> bphdb: INF - Processing "C:\Program Files\VERITAS\NetBackup\DbExt\Oracle\hot_db_backup_db05.cmd"23:46:02.438 [33620.34688] <4> bphdb: INF - bphdb still working.23:47:02.439 [33620.34688] <4> bphdb: INF - bphdb still working.23:48:02.439 [33620.34688] <4> bphdb: INF - bphdb still working.23:49:02.440 [33620.34688] <4> bphdb: INF - bphdb still working.23:50:02.450 [33620.34688] <4> bphdb: INF - bphdb still working.23:51:02.462 [33620.34688] <4> bphdb: INF - bphdb still working.23:52:02.473 [33620.34688] <4> bphdb: INF - bphdb still working.23:53:02.484 [33620.34688] <4> bphdb: INF - bphdb still working.23:54:02.495 [33620.34688] <4> bphdb: INF - bphdb still working.23:55:02.500 [33620.34688] <4> bphdb: INF - bphdb still working.23:55:40.924 [33620.34688] <4> bphdb: INF - command successfully initiated23:55:40.924 [33620.34688] <4> bphdb: INF - bphdb exit normal23:55:40.924 [33620.34688] <4> bphdb: INF - EXIT STATUS 0: the requested operation was successfully completed

[Hristo]

This is part of the dbclient file:

15:20:05.697 [11244.29172] <4> VxBSAInit: Veritas NetBackup XBSA Interface - 6.5  015:20:05.697 [11244.29172] <4> dbc_GetServerClientConfig: ServerName: <sestows140>, ClientName: <sestows48>15:20:05.697 [11244.29172] <8> xbsa_GetEnv: WRN - NBBSA_SCHEDULE not found in environment block15:20:05.697 [11244.29172] <2> int_LogSystemInfo: INF - Veritas NetBackup for Oracle - Release 6.5 (20071115)Platform:WindowsComputer:sestows48OS Release:5.2Build number:3790Misc Info:Service Pack 2User:SYSTEMClient Host:sestows4815:20:05.697 [11244.29172] <2> int_GetMMInfo: INF - support for Proxy Copy enabled15:20:06.104 [11244.29172] <4> int_ProcessCommandString: INF - cmd_key=<NB_ORA_SERV> cmd_val=<sestows140>15:20:06.104 [11244.29172] <2> int_ProcessCommand: INF - Server <sestows140> will be used for this API session.15:20:06.276 [11244.29172] <4> int_ProcessCommandString: INF - cmd_key=<NB_ORA_PLOG> cmd_val=<C:\Program Files\Veritas\NetBackup\logs\user_ops\DHLAdmin\logs\NBWIN004>15:20:06.276 [11244.29172] <2> int_ProcessCommand: INF - Progress Log <C:\Program Files\Veritas\NetBackup\logs\user_ops\DHLAdmin\logs\NBWIN004> will be used for this API session.15:20:16.307 [11244.29172] <2> vnet_vnetd_service_socket: ..\libvlibs\vnet_vnetd.c.2043: VN_REQUEST_SERVICE_SOCKET: 6 0x0000000615:20:16.307 [11244.29172] <2> vnet_vnetd_service_socket: ..\libvlibs\vnet_vnetd.c.2057: service: bprd15:20:16.448 [11244.29172] <2> logconnections: BPRD CONNECT FROM 2.17.14.122.3128 TO 2.17.14.140.1372415:20:16.838 [11244.29172] <2> int_logDateRange: INF - Start Time = 02/01/09 11:30:0915:20:16.838 [11244.29172] <2> int_logDateRange: INF - End Time = 02/03/09 11:30:0915:20:16.838 [11244.29172] <4> VxBSABeginTxn: INF - entering VxBSABeginTxn.15:20:16.838 [11244.29172] <4> VxBSAQueryObject: INF - entering QueryObject.15:20:16.838 [11244.29172] <4> dbc_GetServerClientConfig: ServerName: <sestows140>, ClientName: <sestows48>15:20:16.854 [11244.29172] <2> vnet_vnetd_service_socket: ..\libvlibs\vnet_vnetd.c.2043: VN_REQUEST_SERVICE_SOCKET: 6 0x0000000615:20:16.854 [11244.29172] <2> vnet_vnetd_service_socket: ..\libvlibs\vnet_vnetd.c.2057: service: bprd15:20:16.995 [11244.29172] <2> logconnections: BPRD CONNECT FROM 2.17.14.122.3129 TO 2.17.14.140.1372415:20:17.620 [11244.29172] <2> vnet_vnetd_service_socket: ..\libvlibs\vnet_vnetd.c.2043: VN_REQUEST_SERVICE_SOCKET: 6 0x0000000615:20:17.620 [11244.29172] <2> vnet_vnetd_service_socket: ..\libvlibs\vnet_vnetd.c.2057: service: bprd15:20:17.760 [11244.29172] <2> logconnections: BPRD CONNECT FROM 2.17.14.122.3135 TO 2.17.14.140.1372415:20:18.370 [11244.29172] <2> int_logDateRange: INF - Start Time = 12/26/95 01:00:0015:20:18.370 [11244.29172] <2> int_logDateRange: INF - End Time = 02/03/09 11:30:0915:20:18.370 [11244.29172] <4> VxBSAQueryObject: INF - entering QueryObject.15:20:18.370 [11244.29172] <4> dbc_GetServerClientConfig: ServerName: <sestows140>, ClientName: <sestows48>15:20:18.385 [11244.29172] <2> vnet_vnetd_service_socket: ..\libvlibs\vnet_vnetd.c.2043: VN_REQUEST_SERVICE_SOCKET: 6 0x0000000615:20:18.385 [11244.29172] <2> vnet_vnetd_service_socket: ..\libvlibs\vnet_vnetd.c.2057: service: bprd15:20:18.526 [11244.29172] <2> logconnections: BPRD CONNECT FROM 2.17.14.122.3136 TO 2.17.14.140.1372415:20:19.135 [11244.29172] <2> int_FindBackupImage: INF - bk_urjk6atah_s3955_p1_t677737809 not found15:20:19.135 [11244.29172] <4> VxBSAEndTxn: INF - entering VxBSAEndTxn.15:20:19.135 [11244.29172] <4> VxBSAEndTxn: INF - Transaction being COMMITED.15:20:19.135 [11244.29172] <8> int_SetImageInfoArray: WRN - Backup file, bk_urjk6atah_s3955_p1_t677737809, not found.15:20:19.135 [11244.29172] <2> int_logDateRange: INF - Start Time = 02/01/09 11:30:0815:20:19.135 [11244.29172] <2> int_logDateRange: INF - End Time = 02/03/09 11:30:0815:20:19.135 [11244.29172] <4> VxBSABeginTxn: INF - entering VxBSABeginTxn.15:20:19.135 [11244.29172] <4> VxBSAQueryObject: INF - entering QueryObject.15:20:19.135 [11244.29172] <4> dbc_GetServerClientConfig: ServerName: <sestows140>, ClientName: <sestows48>15:20:19.151 [11244.29172] <2> vnet_vnetd_service_socket: ..\libvlibs\vnet_vnetd.c.2043: VN_REQUEST_SERVICE_SOCKET: 6 0x0000000615:20:19.151 [11244.29172] <2> vnet_vnetd_service_socket: ..\libvlibs\vnet_vnetd.c.2057: service: bprd15:20:19.291 [11244.29172] <2> logconnections: BPRD CONNECT FROM 2.17.14.122.3137 TO 2.17.14.140.1372415:20:19.901 [11244.29172] <2> int_logDateRange: INF - Start Time = 12/26/95 01:00:0015:20:19.901 [11244.29172] <2> int_logDateRange: INF - End Time = 02/03/09 11:30:0815:20:19.901 [11244.29172] <4> VxBSAQueryObject: INF - entering QueryObject.15:20:19.901 [11244.29172] <4> dbc_GetServerClientConfig: ServerName: <sestows140>, ClientName: <sestows48>15:20:19.916 [11244.29172] <2> vnet_vnetd_service_socket: ..\libvlibs\vnet_vnetd.c.2043: VN_REQUEST_SERVICE_SOCKET: 6 0x0000000615:20:19.916 [11244.29172] <2> vnet_vnetd_service_socket: ..\libvlibs\vnet_vnetd.c.2057: service: bprd15:20:20.073 [11244.29172] <2> logconnections: BPRD CONNECT FROM 2.17.14.122.3138 TO 2.17.14.140.1372415:20:20.557 [11244.29172] <2> int_FindBackupImage: INF - bk_urik6atag_s3954_p1_t677737808 not found15:20:20.557 [11244.29172] <4> VxBSAEndTxn: INF - entering VxBSAEndTxn.15:20:20.557 [11244.29172] <4> VxBSAEndTxn: INF - Transaction being COMMITED.15:20:20.557 [11244.29172] <8> int_SetImageInfoArray: WRN - Backup file, bk_urik6atag_s3954_p1_t677737808, not found.15:20:25.432 [11244.29172] <2> int_logDateRange: INF - Start Time = 02/01/09 11:30:0915:20:25.432 [11244.29172] <2> int_logDateRange: INF - End Time = 02/03/09 11:30:0915:20:25.432 [11244.29172] <4> VxBSABeginTxn: INF - entering VxBSABeginTxn.15:20:25.432 [11244.29172] <4> VxBSAQueryObject: INF - entering QueryObject.15:20:25.432 [11244.29172] <4> dbc_GetServerClientConfig: ServerName: <sestows140>, ClientName: <sestows48>15:20:25.448 [11244.29172] <2> vnet_vnetd_service_socket: ..\libvlibs\vnet_vnetd.c.2043: VN_REQUEST_SERVICE_SOCKET: 6 0x0000000615:20:25.448 [11244.29172] <2> vnet_vnetd_service_socket: ..\libvlibs\vnet_vnetd.c.2057: service: bprd15:20:25.651 [11244.29172] <2> logconnections: BPRD CONNECT FROM 2.17.14.122.3142 TO 2.17.14.140.1372415:20:26.245 [11244.29172] <8> VxBSAQueryObject: WRN - Query unsuccessful for client sestows4815:20:26.245 [11244.29172] <4> VxBSAQueryObject: INF - No match was found for query15:20:26.245 [11244.29172] <2> int_logDateRange: INF - Start Time = 12/26/95 01:00:0015:20:26.245 [11244.29172] <2> int_logDateRange: INF - End Time = 02/03/09 11:30:0915:20:26.245 [11244.29172] <4> VxBSAQueryObject: INF - entering QueryObject.15:20:26.245 [11244.29172] <4> dbc_GetServerClientConfig: ServerName: <sestows140>, ClientName: <sestows48>15:20:26.260 [11244.29172] <2> vnet_vnetd_service_socket: ..\libvlibs\vnet_vnetd.c.2043: VN_REQUEST_SERVICE_SOCKET: 6 0x0000000615:20:26.260 [11244.29172] <2> vnet_vnetd_service_socket: ..\libvlibs\vnet_vnetd.c.2057: service: bprd15:20:26.401 [11244.29172] <2> logconnections: BPRD CONNECT FROM 2.17.14.122.3146 TO 2.17.14.140.1372415:20:26.917 [11244.29172] <2> vnet_vnetd_service_socket: ..\libvlibs\vnet_vnetd.c.2043: VN_REQUEST_SERVICE_SOCKET: 6 0x0000000615:20:26.917 [11244.29172] <2> vnet_vnetd_service_socket: ..\libvlibs\vnet_vnetd.c.2057: service: bprd15:20:27.057 [11244.29172] <2> logconnections: BPRD CONNECT FROM 2.17.14.122.3147 TO 2.17.14.140.1372415:20:27.573 [11244.29172] <2> vnet_vnetd_service_socket: ..\libvlibs\vnet_vnetd.c.2043: VN_REQUEST_SERVICE_SOCKET: 6 0x0000000615:20:27.573 [11244.29172] <2> vnet_vnetd_service_socket: ..\libvlibs\vnet_vnetd.c.2057: service: bprd15:20:27.713 [11244.29172] <2> logconnections: BPRD CONNECT FROM 2.17.14.122.3149 TO 2.17.14.140.1372415:20:28.338 [11244.29172] <2> vnet_vnetd_service_socket: ..\libvlibs\vnet_vnetd.c.2043: VN_REQUEST_SERVICE_SOCKET: 6 0x0000000615:20:28.338 [11244.29172] <2> vnet_vnetd_service_socket: ..\libvlibs\vnet_vnetd.c.2057: service: bprd15:20:28.370 [11244.29172] <2> logconnections: BPRD CONNECT FROM 2.17.14.122.3150 TO 2.17.14.140.1372415:20:28.885 [11244.29172] <2> vnet_vnetd_service_socket: ..\libvlibs\vnet_vnetd.c.2043: VN_REQUEST_SERVICE_SOCKET: 6 0x0000000615:20:28.885 [11244.29172] <2> vnet_vnetd_service_socket: ..\libvlibs\vnet_vnetd.c.2057: service: bprd15:20:29.026 [11244.29172] <2> logconnections: BPRD CONNECT FROM 2.17.14.122.3151 TO 2.17.14.140.1372415:20:29.651 [11244.29172] <2> vnet_vnetd_service_socket: ..\libvlibs\vnet_vnetd.c.2043: VN_REQUEST_SERVICE_SOCKET: 6 0x0000000615:20:29.651 [11244.29172] <2> vnet_vnetd_service_socket: ..\libvlibs\vnet_vnetd.c.2057: service: bprd15:20:29.792 [11244.29172] <2> logconnections: BPRD CONNECT FROM 2.17.14.122.3152 TO 2.17.14.140.1372415:20:30.417 [11244.29172] <2> vnet_vnetd_service_socket: ..\libvlibs\vnet_vnetd.c.2043: VN_REQUEST_SERVICE_SOCKET: 6 0x0000000615:20:30.417 [11244.29172] <2> vnet_vnetd_service_socket: ..\libvlibs\vnet_vnetd.c.2057: service: bprd15:20:30.557 [11244.29172] <2> logconnections: BPRD CONNECT FROM 2.17.14.122.3154 TO 2.17.14.140.1372415:20:31.073 [11244.29172] <2> vnet_vnetd_service_socket: ..\libvlibs\vnet_vnetd.c.2043: VN_REQUEST_SERVICE_SOCKET: 6 0x0000000615:20:31.073 [11244.29172] <2> vnet_vnetd_service_socket: ..\libvlibs\vnet_vnetd.c.2057: service: bprd

[marekkedzierski]

Hristo, run this command on the client, if you will not find correct files, nb doesn't have them in catalog databse..

bplist -t 4 -l -R /

[Hristo]

Im getting this on the client:

C:\Program Files\Veritas\NetBackup\bin>bplist -t 4 -l -R /
EXIT STATUS 131: client is not validated to use the server

Regards

H

[Amit_Karia]

Check following

1)Are you having multiple interface in your client..?

2)Also make sure your RMAN script is having NB_ORA_CLIENT and NB_ORA_SERVER...

3)Paste out of last few lines from ur RMAN logs (.out)

[Hristo]

1)Are you having multiple interface in your client..?

Well, we are taking backups of other things than Oracle using NB. I guess that means we have more then one interface?

2)Also make sure your RMAN script is having NB_ORA_CLIENT and NB_ORA_SERVER

It has, but NB_ORA_SERVER is stated as NB_ORA_SERV. Dont know if that makes any difference?

3)Paste out of last few lines from ur RMAN logs (.out)

input archive log thread=3 sequence=2149 recid=5216 stamp=678083710
channel ch00: starting piece 1 at 2009-02-06:04:37:55
channel ch00: finished piece 1 at 2009-02-06:04:40:30
piece handle=arch-s3977-p1 tag=TAG20090206T043517 comment=API Version 2.0,MMS Version 5.0.0.0
channel ch00: backup set complete, elapsed time: 00:02:37
Finished backup at 2009-02-06:04:40:30

List of Archived Log Copies
Key     Thrd Seq     S Low Time            Name
------- ---- ------- - ------------------- ----
5190    1    1558    A 2009-02-02:21:03:22 +ORA_3/db31/archive/arc01558_0646474821.001
5191    3    2141    A 2009-02-02:23:16:27 +ORA_3/db31/archive/arc02141_0646474821.003
deleted archive log
archive log filename=+ORA_3/db31/archive/arc01558_0646474821.001 recid=5190 stamp=677843913
deleted archive log
archive log filename=+ORA_3/db31/archive/arc02141_0646474821.003 recid=5191 stamp=677844458
Deleted 2 objects

released channel: ch00

RMAN>

Recovery Manager complete.
#  

Regards

H

[marekkedzierski]

look at this http://seer.entsupport.symantec.com/docs/250862.htm

[Amit_Karia]

If you are having multiple interface out of which one is exclusively used for nbu then you must

put REQUIRED_INTERFACE =<hostname used for netbackup>

also go to /usr/openv/netbackup/bin

./bpclntcmd -pn

[Hristo]

I got this:

C:\Program Files\Veritas\NetBackup\bin>bpclntcmd -pn

expecting response from server sestows140

SESTOWS48 sestows48 2.xx.14.xxx 4xx8

Regards

H

[Hristo]

I have changed the backupscript so it sends the policy as well. Now I get the below when trying to validate it using RMAN:

RMAN> run2> {3> allocate channel ch1 type 'sbt_tape';4> send 'NB_ORA_POLICY=sestows140_oracle_sestons01_db31,NB_ORA_SERV=sestows140,NB_ORA_CLIENT=sestows48';5> validate backupset 3996;6> } allocated channel: ch1channel ch1: sid=85 instance=db312 devtype=SBT_TAPEchannel ch1: Veritas NetBackup for Oracle - Release 6.5 (20071115) sent command to channel: ch1 channel ch1: starting validation of archive log backupsetchannel ch1: reading from backup piece arch-s4001-p1released channel: ch1RMAN-00571: ===========================================================RMAN-00569: =============== ERROR MESSAGE STACK FOLLOWS ===============RMAN-00571: ===========================================================RMAN-03009: failure of validate command on ch1 channel at 02/11/2009 11:26:21ORA-19870: error reading backup piece arch-s4001-p1ORA-19507: failed to retrieve sequential file, handle="arch-s4001-p1", parms=""ORA-27029: skgfrtrv: sbtrestore returned errorORA-19511: Error received from media manager layer, error text:   Backup file <arch-s4001-p1> not found in NetBackup catalog 

Regards

H

[kfh]

My question about hot rman backups using multiple tape channels failing was an accidental posting here, so I deleted it and posted it as a new discussion.