Video Screencast Help
Symantec Appoints Michael A. Brown CEO. Learn more.

Backup Oracle Fail with error code 25

Created: 30 May 2013 • Updated: 30 May 2013 | 5 comments

Hi all,

 

Our System use 01 Master Netbackup runing version 7.5 and 02 Client running Oracle RAC and Netbackup Client 7.5. I backup file on 02 Client was OK, but  backup Oracle have an error code 25, I can using rman to backup with Netbackup normal. Can you help me to fix this issue

Following is the bpbrm log in our system:

17:21:55.902 [8912.9044] <2> bpbrm main: INITIATING (VERBOSE = 0): version NetBackup 7.5 0
17:21:55.902 [8912.9044] <2> logparams: -backup -S backup -c DB-TrungTam -ct 4 -ru root -cl Oracle_BK -sched Full -bt 1369909315 -dt 0 -st 0 -b DB-TrungTam_1369909315 -mediasvr backup -jobid 122 -jobgrpid 122 -masterversion 750000 -maxfrag 0 -bpstart_time 1369909615 -p 0 -use_ofb -use_otm -jm -secure 1 -kl 28 -rg other -fso -hodb -connect_options 16908802 
17:21:55.902 [8912.9044] <2> read_client: dname=., offline=0, online_at=0 offline_at=0
17:21:55.902 [8912.9044] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0
17:21:55.902 [8912.9044] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0
17:21:55.902 [8912.9044] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0
17:21:55.902 [8912.9044] <2> read_client: dname=OA_2147483647, offline=0, online_at=2147483647 offline_at=0
17:21:55.902 [8912.9044] <2> db_freeEXDB_INFO: ?
17:21:55.902 [8912.9044] <2> bpbrm create_events: created suspend event name Global\NetBackup Suspend Event, pid: 8912
17:21:55.902 [8912.9044] <2> job_connect: SO_KEEPALIVE set on socket 604 for client backup
17:21:55.902 [8912.9044] <2> logconnections: BPJOBD CONNECT FROM 192.168.14.2.59113 TO 192.168.14.2.13723 fd = 604
17:21:55.902 [8912.9044] <2> job_authenticate_connection: ignoring VxSS authentication check for now...
17:21:55.902 [8912.9044] <2> job_connect: Connected to the host backup contype 53 jobid <122> socket <604>
17:21:55.918 [8912.9044] <2> job_connect: Connected on port 59113
17:21:55.918 [8912.9044] <8> file_to_cache_item: [vnet_addrinfo.c:6555] fopen() failed ERRNO=2 FILE=C:\Program Files\Veritas\NetBackup\var\host_cache\0dc\c507aadc+veritas_pbx,1,400,2,1,0+db-trungtam.txt
17:21:55.918 [8912.9044] <8> file_to_cache_item: [vnet_addrinfo.c:6555] fopen() failed ERRNO=2 FILE=C:\Program Files\Veritas\NetBackup\var\host_cache\12c\9deddb2c+0,1,a,0,1,0+192.168.12.2.txt
17:21:55.918 [8912.9044] <8> file_to_cache_item: [vnet_addrinfo.c:6555] fopen() failed ERRNO=2 FILE=C:\Program Files\Veritas\NetBackup\var\host_cache\0dc\c507aadc+vnetd,1,400,2,1,0+db-trungtam.txt
17:21:55.934 [8912.9044] <8> file_to_cache_item: [vnet_addrinfo.c:6555] fopen() failed ERRNO=2 FILE=C:\Program Files\Veritas\NetBackup\var\host_cache\0dc\c507aadc+bpcd,1,400,2,1,0+db-trungtam.txt
17:21:55.949 [8912.9044] <2> vnet_pbxConnect: pbxConnectEx Succeeded
17:21:55.949 [8912.9044] <2> logconnections: BPCD CONNECT FROM 192.168.14.2.59114 TO 192.168.12.2.1556 fd = 660
17:21:55.949 [8912.9044] <8> file_to_cache_item: [vnet_addrinfo.c:6555] fopen() failed ERRNO=2 FILE=C:\Program Files\Veritas\NetBackup\var\host_cache\12c\9deddb2c+veritas_pbx,1,400,2,1,0+192.168.12.2.txt
17:21:55.949 [8912.9044] <8> file_to_cache_item: [vnet_addrinfo.c:6555] fopen() failed ERRNO=2 FILE=C:\Program Files\Veritas\NetBackup\var\host_cache\12c\9deddb2c+vnetd,1,400,2,1,0+192.168.12.2.txt
17:21:55.949 [8912.9044] <2> vnet_pbxConnect: pbxConnectEx Succeeded
17:21:55.949 [8912.9044] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 192.168.14.2.59115 TO 192.168.12.2.1556 fd = 668
17:21:55.965 [8912.9044] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa
17:21:55.996 [8912.9044] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string /tmp/vnet-30345369909281901944000000000-a2NhGX
17:21:56.636 [8912.9044] <2> bpcr_get_version_rqst: bpcd version: 07500000
17:21:56.854 [8912.9044] <2> bpcr_get_charset_rqst: bpcd charset: 00000001
17:21:56.854 [8912.9044] <2> ConnectionCache::connectAndCache: Acquiring new connection for host backup, query type 89
17:21:56.854 [8912.9044] <2> logconnections: BPDBM CONNECT FROM 192.168.14.2.59116 TO 192.168.14.2.13721 fd = 672
17:21:56.854 [8912.9044] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2
17:21:56.901 [8912.9044] <2> db_end: Need to collect reply
17:21:56.994 [8912.9044] <2> logconnections: BPCD CONNECT FROM 192.168.14.2.632 TO 192.168.12.2.13782 fd = 668
17:21:56.994 [8912.9044] <8> vnet_vnetd_push_ipaddr: [vnet_vnetd.c:1813] sizeof (name_in->sin_addr.s_addr) 4 0x4
17:21:56.994 [8912.9044] <8> vnet_vnetd_push_ipaddr: [vnet_vnetd.c:1821] bptr[i] 192 0xc0
17:21:56.994 [8912.9044] <8> vnet_vnetd_push_ipaddr: [vnet_vnetd.c:1821] bptr[i] 168 0xa8
17:21:56.994 [8912.9044] <8> vnet_vnetd_push_ipaddr: [vnet_vnetd.c:1821] bptr[i] 14 0xe
17:21:56.994 [8912.9044] <8> vnet_vnetd_push_ipaddr: [vnet_vnetd.c:1821] bptr[i] 2 0x2
17:21:56.994 [8912.9044] <8> vnet_vnetd_push_ipaddr: [vnet_vnetd.c:1823] i 4 0x4
17:21:56.994 [8912.9044] <8> vnet_vnetd_push_ipaddr: [vnet_vnetd.c:1834] vnetd_port 13724 0x359c
17:21:56.994 [8912.9044] <8> vnet_begin_connect_back: [vnet_vnetd.c:602] ipc_string 59117
17:21:57.275 [8912.9044] <8> vnet_begin_connect_back: [vnet_vnetd.c:614] pstatus 0 0x0
17:21:57.494 [8912.9044] <8> vnet_begin_connect_back: [vnet_vnetd.c:633] popped hash_str1 4d94e54fcf7b9df7d703e1171abebbac
17:21:57.494 [8912.9044] <8> verify_hashes: [vnet_vnetd.c:1641] hash_str 4d94e54fcf7b9df7d703e1171abebbac
17:21:57.494 [8912.9044] <2> bpbrm start_bpcd_stat: DATA_SOCK from bpcr = 668
17:21:57.494 [8912.9044] <2> bpbrm start_bpcd_stat: NAME_SOCK from bpcr = 660
17:21:57.494 [8912.9044] <2> bpbrm main: received bpcd success message
17:21:57.712 [8912.9044] <2> bpbrm main: client_pid=30347
17:21:57.712 [8912.9044] <2> bpbrm main: from client DB-TrungTam: read client start message
17:21:57.712 [8912.9044] <2> bpbrm write_continue_backup: wrote CONTINUE BACKUP on COMM_SOCK <668>
17:21:57.712 [8912.9044] <2> write_file_names: buffering file name '3a44424e414d453a6d6f697464623a4442484f4d453a2f7530312f6170702f6f7261636c652f31312e322e302f64623a44424e45544e414d453a6d6f69746462:DBTEMPLATENAME:Oracle_BK.tpl' for output
17:21:57.712 [8912.9044] <2> write_file_names: successfully wrote buffer to COMM_SOCK
17:21:57.712 [8912.9044] <2> bpbrm main: wrote CONTINUE on COMM_SOCK
17:21:57.712 [8912.9044] <2> bpbrm main: closing COMM_SOCK
17:22:17.181 [9660.6276] <2> bpbrm main: INITIATING (VERBOSE = 0): version NetBackup 7.5 0
17:22:17.181 [9660.6276] <2> logparams: -backup -S backup -c DB-TrungTam -ct 4 -ru oracle -cl Oracle_BK -sched Default-Application-Backup -bt 1369909336 -dt 0 -st 2 -b DB-TrungTam_1369909336 -mediasvr backup -jobid 123 -jobgrpid 122 -masterversion 750000 -maxfrag 1048576 -bpstart_time 1369909637 -reqid -1369909244 -mt 2 -to 0 -stunit backup-hcart2-robot-tld-0 -rl 3 -rp 2678400 -eari 0 -cj 2 -D 14 -rt 8 -rn 0 -pool DB_Backup -use_ofb -use_otm -jm -secure 1 -kl 28 -rg asmadmin -fso -keyword moitdb -hodb -cfc -L /usr/openv/netbackup/logs/user_ops/dbext/logs/2443.0.1369909298 -connect_options 16908802 
17:22:17.196 [9660.6276] <2> read_client: dname=., offline=0, online_at=0 offline_at=0
17:22:17.196 [9660.6276] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0
17:22:17.196 [9660.6276] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0
17:22:17.196 [9660.6276] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0
17:22:17.196 [9660.6276] <2> read_client: dname=OA_2147483647, offline=0, online_at=2147483647 offline_at=0
17:22:17.196 [9660.6276] <2> db_freeEXDB_INFO: ?
17:22:17.196 [9660.6276] <2> bpbrm create_events: created suspend event name Global\NetBackup Suspend Event, pid: 9660
17:22:17.196 [9660.6276] <2> job_connect: SO_KEEPALIVE set on socket 604 for client backup
17:22:17.196 [9660.6276] <2> logconnections: BPJOBD CONNECT FROM 192.168.14.2.59164 TO 192.168.14.2.13723 fd = 604
17:22:17.196 [9660.6276] <2> job_authenticate_connection: ignoring VxSS authentication check for now...
17:22:17.196 [9660.6276] <2> job_connect: Connected to the host backup contype 53 jobid <123> socket <604>
17:22:17.196 [9660.6276] <2> job_connect: Connected on port 59164
17:22:17.212 [9660.6276] <2> vnet_pbxConnect: pbxConnectEx Succeeded
17:22:17.212 [9660.6276] <2> logconnections: BPCD CONNECT FROM 192.168.14.2.59166 TO 192.168.12.2.1556 fd = 636
17:22:17.228 [9660.6276] <2> vnet_pbxConnect: pbxConnectEx Succeeded
17:22:17.228 [9660.6276] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM 192.168.14.2.59168 TO 192.168.12.2.1556 fd = 644
17:22:17.228 [9660.6276] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa
17:22:17.259 [9660.6276] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string /tmp/vnet-30399369909303169002000000000-jECv8e
17:22:17.883 [9660.6276] <2> bpcr_get_version_rqst: bpcd version: 07500000
17:22:18.086 [9660.6276] <2> bpcr_get_charset_rqst: bpcd charset: 00000001
17:22:18.086 [9660.6276] <2> ConnectionCache::connectAndCache: Acquiring new connection for host backup, query type 89
17:22:18.086 [9660.6276] <2> logconnections: BPDBM CONNECT FROM 192.168.14.2.59169 TO 192.168.14.2.13721 fd = 644
17:22:18.086 [9660.6276] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2
17:22:18.132 [9660.6276] <2> db_end: Need to collect reply
17:22:18.195 [9660.6276] <2> bpbrm listen_for_client: HOT_ORACLE_DB_BACKUP
17:22:18.195 [9660.6276] <2> logconnections: BPCD CONNECT FROM 192.168.14.2.958 TO 192.168.12.2.13782 fd = 648
17:22:18.195 [9660.6276] <8> vnet_vnetd_push_ipaddr: [vnet_vnetd.c:1813] sizeof (name_in->sin_addr.s_addr) 4 0x4
17:22:18.195 [9660.6276] <8> vnet_vnetd_push_ipaddr: [vnet_vnetd.c:1821] bptr[i] 192 0xc0
17:22:18.195 [9660.6276] <8> vnet_vnetd_push_ipaddr: [vnet_vnetd.c:1821] bptr[i] 168 0xa8
17:22:18.195 [9660.6276] <8> vnet_vnetd_push_ipaddr: [vnet_vnetd.c:1821] bptr[i] 14 0xe
17:22:18.195 [9660.6276] <8> vnet_vnetd_push_ipaddr: [vnet_vnetd.c:1821] bptr[i] 2 0x2
17:22:18.195 [9660.6276] <8> vnet_vnetd_push_ipaddr: [vnet_vnetd.c:1823] i 4 0x4
17:22:18.195 [9660.6276] <8> vnet_vnetd_push_ipaddr: [vnet_vnetd.c:1834] vnetd_port 13724 0x359c
17:22:18.195 [9660.6276] <8> vnet_begin_connect_back: [vnet_vnetd.c:602] ipc_string 59173
17:22:18.476 [9660.6276] <8> vnet_begin_connect_back: [vnet_vnetd.c:614] pstatus 0 0x0
17:22:18.694 [9660.6276] <8> vnet_begin_connect_back: [vnet_vnetd.c:633] popped hash_str1 840e77ffe0c525d18d4f5ef4dbd91227
17:22:18.694 [9660.6276] <8> verify_hashes: [vnet_vnetd.c:1641] hash_str 840e77ffe0c525d18d4f5ef4dbd91227
17:22:18.694 [9660.6276] <2> bpbrm send_info_via_progress_file: CLIENT_CMD_SOCK from bpcr = 648
17:22:18.694 [9660.6276] <2> bpbrm send_info_via_progress_file: CLIENT_STAT_SOCK from bpcr = 664
17:22:19.131 [9660.6276] <2> bpcr_get_peername_rqst: Server peername length = 6
17:22:19.349 [9660.6276] <2> bpbrm write_msg_to_progress_file: INF - Data socket = BACKUP.IPC:59171
 
17:22:19.568 [9660.6276] <2> bpbrm write_msg_to_progress_file: INF - Name socket = BACKUP.IPC:59172
 
17:22:19.786 [9660.6276] <2> bpbrm write_msg_to_progress_file: INF - Job id = 123
 
17:22:20.004 [9660.6276] <2> bpbrm write_msg_to_progress_file: INF - Backup id = DB-TrungTam_1369909336
 
17:22:20.207 [9660.6276] <2> bpbrm write_msg_to_progress_file: INF - Backup time = 1369909336
 
17:22:20.426 [9660.6276] <2> bpbrm write_msg_to_progress_file: INF - Policy name = Oracle_BK
 
17:22:20.628 [9660.6276] <2> bpbrm write_msg_to_progress_file: INF - Snapshot = 0
 
17:22:20.831 [9660.6276] <2> bpbrm write_msg_to_progress_file: INF - Frozen image = 0
 
17:22:21.034 [9660.6276] <2> bpbrm write_msg_to_progress_file: INF - Backup copy = 0
 
17:22:21.237 [9660.6276] <2> bpbrm write_msg_to_progress_file: INF - Master server = backup
 
17:22:21.455 [9660.6276] <2> bpbrm write_msg_to_progress_file: INF - Media server = backup
 
17:22:21.658 [9660.6276] <2> bpbrm write_msg_to_progress_file: INF - Multiplexing = 0
 
17:22:21.876 [9660.6276] <2> bpbrm write_msg_to_progress_file: INF - New data socket = BACKUP.IPC:59170
 
17:22:22.095 [9660.6276] <2> bpbrm write_msg_to_progress_file: INF - Encrypt = 0
 
17:22:22.298 [9660.6276] <2> bpbrm write_msg_to_progress_file: INF - Use shared memory = 0
 
17:22:22.516 [9660.6276] <2> bpbrm write_msg_to_progress_file: INF - Compression = 0
 
17:22:22.734 [9660.6276] <2> bpbrm write_msg_to_progress_file: INF - Encrypt = 0
 
17:22:22.937 [9660.6276] <2> bpbrm write_msg_to_progress_file: INF - Keep logs = 28
 
17:22:23.156 [9660.6276] <2> bpbrm write_msg_to_progress_file: INF - Client read timeout = 300
 
17:22:23.358 [9660.6276] <2> bpbrm write_msg_to_progress_file: INF - Media mount timeout = 0
 
17:23:23.809 [9660.6276] <8> vnet_sock_ready: [vnet.c:1485] max_time=60 sock=640
17:23:23.809 [9660.6276] <2> vnet_sock_ready: vnet.c.1486: 0: Function failed: 11 0x0000000b
17:23:23.809 [9660.6276] <8> vnet_accept_from_vnetd: [vnet_vnetd.c:970] status 0 0x0
17:24:23.822 [9660.6276] <8> vnet_sock_ready: [vnet.c:1485] max_time=60 sock=640
17:24:23.822 [9660.6276] <2> vnet_sock_ready: vnet.c.1486: 0: Function failed: 11 0x0000000b
17:24:23.822 [9660.6276] <8> vnet_accept_from_vnetd: [vnet_vnetd.c:970] status 0 0x0
17:24:23.822 [9660.6276] <8> vnet_vnetd_accept_and_receive: [vnet_vnetd.c:1942] vnet_accept_from_vnetd timedout 11 0xb
17:24:23.822 [9660.6276] <16> bpbrm listen_for_client: listen for client protocol error - couldn't accept from data socket, The operation completed successfully.  (0)
17:24:23.822 [9660.6276] <2> bpbrm kill_child_process_Ex: start
17:24:23.822 [9660.6276] <2> bpbrm Exit: client backup EXIT STATUS 25: cannot connect on socket
 
Regards,
Operating Systems:

Comments 5 CommentsJump to latest comment

RamNagalla's picture

does the media server that you used for normal file and Oracle are same?

how is the communication between the client and Master server?

what is the output of bpclntcmd -pn in Client?

khanhb@hpt.vn's picture

Hi Nagalla,

 

- I dont't understand your first question???

- Communication between the client and master is OK. because i can backup nomal file from client to master server and i can run rman script of netbackup from client site

- Output of bpclntcmd -pn in client is:

[oracle@DB-TrungTam ~]$ /usr/openv/netbackup/bin/bpclntcmd -pn
expecting response from server BACKUP
DB-TrungTam DB-TrungTam 192.168.12.2 31018
 
Thanks
 
Marianne's picture

OK - so you have a single master/media server? (as opposed to master with multiple media server).

We will need all of the following logs to find out where exactly the failure is happening:

On client: bpcd and dbclient (dbclient log must have 777 permission)

On master server: bprd (if log folder does not exist, create it and restart NBU)

On media server (well, master if you have master/media): bpbrm

Please copy logs to reflect process name (e.g. bprd.txt) and post as File attachments.

Supporting Storage Foundation and VCS on Unix and Windows as well as NetBackup on Unix and Windows
Handy NBU Links

khanhb@hpt.vn's picture

Hi Marianne,

 

Thanks for your reply, attached is log file that you asked me but I cannot collect bpcd log (I've create bpcd folder under /usr/openv/netbackup/logs)

 

Thanks,

AttachmentSize
NBU_LOG.rar 173.35 KB
Will Restore's picture

bpbrm Exit: client backup EXIT STATUS 25: cannot connect on socket

...

bpbrm write_msg_to_progress_file: INF - Client read timeout = 300

 

 

1 In the NetBackup Administration Console, expand NetBackupManagement > Host Properties > Clients

2 Set the Client read timeout property to a large value.

The default for the Client read timeout setting is 300 seconds (5 minutes).

For database agent clients, increase the value significantly from the recommended value.

See the NetBackup Administrator’s Guide, Volume 1.

For example, change this setting to 1800 seconds (30 minutes) to minimize timeout errors.

3 Click OK for each client.

Will Restore -- where there is a Will there is a way