Veritas-bu
[Top] [All Lists]
<prev [Date] next>
[Advanced]
 <prev [Thread] next>

Re: [Veritas-bu] restore failed to recover the requested files

2007-10-10 04:36:38
Subject: Re: [Veritas-bu] restore failed to recover the requested files
From: Justin Piszcz <jpiszcz AT lucidpixels DOT com>
To: prashant dabre <netbackup-forum AT backupcentral DOT com>
Date: Wed, 10 Oct 2007 04:15:12 -0400 (EDT) 
Curious, does it work if you restore the same data from the master server 
using jnbSA?

Justin.

On Tue, 9 Oct 2007, prashant dabre wrote:

>
> I am using netbackup 6.0 . When I tried to recover one folder , it has failed 
> with following error message
>
> -----------------------------------------------------------------------------------------------------------------------
> 04:04:04 (5445.001) Status of restore from image created Sat 06 Oct 2007 
> 06:07:43 PM EDT = network connection timed out
>
> 04:04:05 (5445.xxx) INF - Status = the restore failed to recover the 
> requested files.
> -----------------------------------------------------------------------------------------------------------------------
>
> I am able to telnet on port 13722,13724, 13783 from both side (server & 
> client)
>
> Got correct responce bpclntcmd command from client.
> -----------------------------------------------------
> /usr/openv/netbackup/bin/bpclntcmd -pn
> expecting response from server netbackup
> mail-backup mail-backup 10.x.x.x 56445
> -----------------------------------------------------
>
> Can anybody help me to debug this problem ?
>
>
> I have increased the log level to 5 . I am not able to figure out the exact 
> reason of failure from log file.
>
> Following is the bptm-logs
> -------------------------------------------------------------------------------------------------
> 02:22:13.454 [3010] <2> set_job_details: Done
> 02:22:13.454 [3010] <2> append_to_client_log: dbmisc.c.2974: about to call 
> ConnectToBPCD connect_opts = 0xffffffff
> 02:22:13.454 [3010] <2> read_client: ?
> 02:22:13.454 [3010] <2> read_client: cannot access() netbackup: No such file 
> or directory (2)
> 02:22:13.454 [3010] <2> ConnectToBPCD: db_getCLIENT(netbackup) failed: 227
> 02:22:13.454 [3010] <2> hosts_equal: Comparing hosts <netbackup> and 
> <netbackup>
> 02:22:13.454 [3010] <2> hosts_equal: names are the same
> 02:22:13.454 [3010] <2> bpcr_connect: bpcr.c.341: connect_opts = 0x01000100 
> connect_opts2 = 0x01000100
> 02:22:13.454 [3010] <2> bpcr_connect: bpcr.c.348: connect_opts = 0x01000100
> 02:22:13.454 [3010] <2> bpcr_connect: bpcr.c.387: daemon_port_type = 0
> 02:22:13.485 [3010] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2034: 
> VN_REQUEST_SERVICE_SOCKET: 6 0x00000006
> 02:22:13.485 [3010] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2048: 
> service: bpcd
> 02:22:13.486 [3010] <2> vnet_async_connect: vnet_vnetd.c.3922: immediate 
> connect: 0 0x00000000
> 02:22:13.486 [3010] <2> vnet_async_connect: vnet_vnetd.c.3925: connect: 
> immediate CONNECT FROM 10.25.19.75.45447 TO 10.25.19.75.13724 fd = 14
> 02:22:13.486 [3010] <2> logconnections: BPCD CONNECT FROM 10.25.19.75.45447 
> TO 10.25.19.75.13724
> 02:22:13.486 [3010] <2> vauth_authentication_required: vauth_comm.c.789: no 
> methods for address: no authentication required
> 02:22:13.486 [3010] <2> vauth_connector: vauth_comm.c.200: no methods for 
> address: no authentication required
> 02:22:13.486 [3010] <2> bpcr_authenticate_connection: no authentication 
> required
> 02:22:13.487 [3010] <2> vnet_connect_to_vnetd_extra: vnet_vnetd.c.178: msg: 
> VNETD CONNECT FROM 10.25.19.75.45448 TO 10.25.19.75.13724 fd = 15
> 02:22:13.517 [3010] <2> vnet_vnetd_connect_forward_socket_end: 
> vnet_vnetd.c.529: VN_REQUEST_CONNECT_FORWARD_SOCKET: 10 0x0000000a
> 02:22:13.519 [3010] <2> vnet_vnetd_connect_forward_socket_end: 
> vnet_vnetd.c.546: ipc_string: /tmp/vnet-03068191910933517665000000000-nqaG_f
> 02:22:13.519 [3010] <2> vnet_vnetd_connect_forward_socket_end: 
> vnet_vnetd.c.557: hash_str1: 7f1f441b77e6115a5e08bf2c7d2295bf
> 02:22:13.578 [3010] <2> db_error_add_to_file: dberrorq.c:midnite = 1191902400
> 02:22:13.601 [3010] <4> mpx_issue_begin_msg: begin reading backup id 
> mailsafe-backup_1191708463 (restore), copy 1, fragment 1 from media id NOR331 
> on drive index 1
> 02:22:13.601 [3010] <2> send_brm_msg: CURRENT POSITION NOR331 36
> 02:22:13.601 [3010] <2> process_brm_msg: no pending message from bpbrm
> 02:22:14.613 [3010] <2> read_brm_msg: CONTINUE RESTORE
> 02:22:14.653 [3010] <2> send_brm_msg: MEDIA READY
> 02:22:14.653 [3010] <2> mpx_read_data: begin reading data from media id 
> NOR331, file num 36
> 02:22:14.656 [3010] <2> get_tape_position_for_read: absolute block position 
> prior to reading is 1130009
> 02:22:14.724 [3018] <2> skip_bytes: [3010] skipping 62464 bytes, input length 
> was 62464, SAVE_BYTES = 0, file = ?
> 02:22:14.724 [3018] <2> filter_image: [3010] Min_records before frag switch 
> is 90000, locate available = 1
> 02:22:14.724 [3018] <2> get_next_file: [3010] received string: (3350 1416058 
> 0 0 0 0 0 /var/spool/mailsafe/1/)
> 02:22:14.724 [3018] <2> write_bytes: [3010] writing 512 data bytes, input 
> length was 512, SAVE_BYTES = 0, file = /var/spool/mailsafe/1/
> 02:22:14.726 [3018] <2> get_next_file: [3010] received string: (3351 1416059 
> 0 0 0 0 24603 /var/spool/mailsafe/1/2.gz)
> 02:22:14.726 [3018] <2> write_bytes: [3010] writing 25600 data bytes, input 
> length was 25115, SAVE_BYTES = 0, file = /var/spool/mailsafe/1/2.gz
> 02:22:14.737 [3018] <2> get_next_file: [3010] received string: (3352 1416109 
> 0 0 0 0 2104 /var/spool/mailsafe/1/3.gz)
> 02:22:14.737 [3018] <2> write_bytes: [3010] writing 3072 data bytes, input 
> length was 2616, SAVE_BYTES = 0, file = /var/spool/mailsafe/1/3.gz
> 02:22:14.737 [3018] <2> get_next_file: [3010] received string: (3353 1416115 
> 0 0 0 0 2108 /var/spool/mailsafe/1/4.gz)
> 02:22:14.737 [3018] <2> write_bytes: [3010] writing 3072 data bytes, input 
> length was 2620, SAVE_BYTES = 0, file = /var/spool/mailsafe/1/4.gz
> 02:22:14.737 [3018] <2> get_next_file: [3010] received string: (3354 1416121 
> 0 0 0 0 2099 /var/spool/mailsafe/1/5.gz)
> 02:22:14.737 [3018] <2> write_bytes: [3010] writing 3072 data bytes, input 
> length was 2611, SAVE_BYTES = 0, file = /var/spool/mailsafe/1/5.gz
> 02:22:14.737 [3018] <2> get_next_file: [3010] received string: (3355 1416127 
> 0 0 0 0 2102 /var/spool/mailsafe/1/6.gz)
> 02:22:14.737 [3018] <2> write_bytes: [3010] writing 3072 data bytes, input 
> length was 2614, SAVE_BYTES = 0, file = /var/spool/mailsafe/1/6.gz
> 02:22:14.737 [3018] <2> get_next_file: [3010] received string: (3356 1416133 
> 0 0 0 0 2255 /var/spool/mailsafe/1/7.gz)
> 02:22:14.737 [3018] <2> write_bytes: [3010] writing 3072 data bytes, input 
> length was 2767, SAVE_BYTES = 0, file = /var/spool/mailsafe/1/7.gz
> 02:22:14.737 [3018] <2> get_next_file: [3010] received string: (3357 1416139 
> 0 0 0 0 2077 /var/spool/mailsafe/1/8.gz)
> 02:22:14.737 [3018] <2> write_bytes: [3010] writing 3072 data bytes, input 
> length was 2589, SAVE_BYTES = 0, file = /var/spool/mailsafe/1/8.gz
> 02:22:14.737 [3018] <2> get_next_file: [3010] received string: (3358 1416145 
> 0 0 0 0 11540623 /var/spool/mailsafe/1/9.gz)
> 02:22:14.737 [3018] <2> write_bytes: [3010] writing 11541504 data bytes, 
> input length was 11541135, SAVE_BYTES = 0, file = /var/spool/mailsafe/1/9.gz
> 02:23:13.524 [3107] <2> bptm: INITIATING (VERBOSE = 5): -delete_expired
> 02:23:13.533 [3107] <2> nbconf_get_info: nbconf_glue.cpp.237: NBCONF_LIB: 
> /usr/openv/lib/libVnbconf.so
> 02:23:13.534 [3107] <2> bptm: EXITING with status 0 <----------
> 02:23:13.837 [3113] <2> bptm: INITIATING (VERBOSE = 5): -delete_all_expired
> 02:23:13.847 [3113] <2> nbconf_get_info: nbconf_glue.cpp.237: NBCONF_LIB: 
> /usr/openv/lib/libVnbconf.so
> 02:23:13.847 [3113] <2> bptm: PORT_STATUS = 0x00000000
> 02:23:13.847 [3113] <2> bptm: EMMserver_name = netbackup
> 02:23:13.847 [3113] <2> bptm: EMMserver_port = 1556
> 02:23:13.859 [3113] <2> nbconf_get_info: nbconf_glue.cpp.237: NBCONF_LIB: 
> /usr/openv/lib/libVnbconf.so
> 02:23:13.861 [3113] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: 
> VssGetFQDNHostName. Search name
> 02:23:13.861 [3113] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using 
> Cached entries FALSE
> 02:23:13.862 [3113] <2> Human Message: Optional VxSS libraries not 
> initialized.
> 02:23:13.862 [3113] <2> VssCleanUp: vss_auth.cpp.854: Function: VssCleanUp 
> result: 21
> 02:23:13.896 [3113] <2> nbconf_get_info: nbconf_glue.cpp.237: NBCONF_LIB: 
> /usr/openv/lib/libVnbconf.so
> 02:23:13.900 [3113] <2> vnet_cached_gethostbyname: vnet_hosts.c.339: h_errno: 
> 1 0x00000001
> 02:23:13.906 [3113] <2> vnet_cached_gethostbyname: vnet_hosts.c.340: 
> gethostbyname failed: add_media_server_on_clients
> 02:23:13.906 [3113] <2> vnet_cached_gethostbyname: vnet_hosts.c.353: Function 
> failed: 6 0x00000006
> 02:23:13.908 [3113] <2> vnet_cached_gethostbyname: vnet_hosts.c.339: h_errno: 
> 1 0x00000001
> 02:23:13.908 [3113] <2> vnet_cached_gethostbyname: vnet_hosts.c.340: 
> gethostbyname failed: bptm
> 02:23:13.908 [3113] <2> vnet_cached_gethostbyname: vnet_hosts.c.353: Function 
> failed: 6 0x00000006
> 02:23:13.912 [3113] <2> vnet_cached_gethostbyname: vnet_hosts.c.286: host in 
> failed cache: add_media_server_on_clients
> 02:23:13.912 [3113] <2> vnet_cached_gethostbyname: vnet_hosts.c.287: Function 
> failed: 5 0x00000005
> 02:23:13.912 [3113] <2> vnet_cached_gethostbyname: vnet_hosts.c.286: host in 
> failed cache: bptm
> 02:23:13.912 [3113] <2> vnet_cached_gethostbyname: vnet_hosts.c.287: Function 
> failed: 5 0x00000005
> 02:23:13.914 [3113] <2> vnet_cached_gethostbyname: vnet_hosts.c.286: host in 
> failed cache: add_media_server_on_clients
> 02:23:13.914 [3113] <2> vnet_cached_gethostbyname: vnet_hosts.c.287: Function 
> failed: 5 0x00000005
> 02:23:13.914 [3113] <2> vnet_cached_gethostbyname: vnet_hosts.c.286: host in 
> failed cache: bptm
> 02:23:13.914 [3113] <2> vnet_cached_gethostbyname: vnet_hosts.c.287: Function 
> failed: 5 0x00000005
> 02:23:13.915 [3113] <2> vnet_cached_gethostbyname: vnet_hosts.c.286: host in 
> failed cache: add_media_server_on_clients
> 02:23:13.915 [3113] <2> vnet_cached_gethostbyname: vnet_hosts.c.287: Function 
> failed: 5 0x00000005
> 02:23:13.915 [3113] <2> vnet_cached_gethostbyname: vnet_hosts.c.286: host in 
> failed cache: bptm
> 02:23:13.915 [3113] <2> vnet_cached_gethostbyname: vnet_hosts.c.287: Function 
> failed: 5 0x00000005
> 02:23:13.916 [3113] <2> vnet_cached_gethostbyname: vnet_hosts.c.286: host in 
> failed cache: add_media_server_on_clients
> 02:23:13.916 [3113] <2> vnet_cached_gethostbyname: vnet_hosts.c.287: Function 
> failed: 5 0x00000005
> 02:23:13.916 [3113] <2> vnet_cached_gethostbyname: vnet_hosts.c.286: host in 
> failed cache: bptm
> 02:23:13.916 [3113] <2> vnet_cached_gethostbyname: vnet_hosts.c.287: Function 
> failed: 5 0x00000005
> 02:23:13.955 [3113] <2> bptm: EXITING with status 0 <----------
> 02:23:25.748 [3136] <2> bptm: INITIATING (VERBOSE = 5): -rptdrv -jobid 
> -1188490786 -jm
> 02:23:25.757 [3136] <2> nbconf_get_info: nbconf_glue.cpp.237: NBCONF_LIB: 
> /usr/openv/lib/libVnbconf.so
> 02:23:25.758 [3136] <2> bptm: PORT_STATUS = 0x00000000
> 02:23:25.758 [3136] <2> bptm: EMMserver_name = netbackup
> 02:23:25.758 [3136] <2> bptm: EMMserver_port = 1556
> 02:23:25.758 [3136] <2> drivename_open: Called with Create 0, file 
> IBM.ULTRIUM-TD3.001
> 02:23:25.758 [3136] <2> drivename_checklock: Called
> 02:23:25.758 [3136] <2> drivename_checklock: PID 3010 has lock
> 02:23:25.759 [3136] <2> report_drives: MODE = 0
> 02:23:25.759 [3136] <2> report_drives: TIME = 1191910862
> 02:23:25.759 [3136] <2> report_drives: MASTER = netbackup
> 02:23:25.759 [3136] <2> report_drives: PATH = /dev/rmt/2cbn
> 02:23:25.759 [3136] <2> report_drives: MEDIA = NOR331
> 02:23:25.759 [3136] <2> report_drives: REQID = 5463
> 02:23:25.759 [3136] <2> report_drives: ALOCID = 1914
> 02:23:25.759 [3136] <2> report_drives: RBID = 
> {CE3FAEE2-1DD1-11B2-8269-0003BA163B56}
> 02:23:25.759 [3136] <2> report_drives: PID = 3010
> 02:23:25.759 [3136] <2> report_drives: FILE = 
> /usr/openv/netbackup/db/media/tpreq/drive_IBM.ULTRIUM-TD3.001
> 02:23:25.759 [3136] <2> main: Sending [EXIT STATUS 0] to NBJM
> 02:23:25.759 [3136] <2> bptm: EXITING with status 0 <----------
>
> +----------------------------------------------------------------------
> |This was sent by prashant.dabre AT gmail DOT com via Backup Central.
> |Forward SPAM to abuse AT backupcentral DOT com.
> +----------------------------------------------------------------------
>
>
> _______________________________________________
> Veritas-bu maillist  -  Veritas-bu AT mailman.eng.auburn DOT edu
> http://mailman.eng.auburn.edu/mailman/listinfo/veritas-bu
>
_______________________________________________
Veritas-bu maillist  -  Veritas-bu AT mailman.eng.auburn DOT edu
http://mailman.eng.auburn.edu/mailman/listinfo/veritas-bu
[More with this subject...]
<Prev in Thread] Current Thread [Next in Thread>
Previous by Date:  [Veritas-bu] restore failed to recover the requested files, prashant dabre
Next by Date:  [Veritas-bu] NetBackup 6.5 for VMware - Failed to get VM Server Info List, frankm
Previous by Thread:  [Veritas-bu] restore failed to recover the requested files, prashant dabre
Next by Thread:  [Veritas-bu] restore failed to recover the requested files, prashant dabre
Indexes:  [Date] [Thread] [Top] [All Lists]