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
|
