cancel
Turn on suggestions
Auto-suggest helps you quickly narrow down your search results by suggesting possible matches as you type.
Showing results for 
Search instead for 
Did you mean: 
Veritas.com
Support

Error code 84 when fullbackup VM client

pstandpon
Level 2

‎05-15-2012 03:53 AM

 

Hi ,

          We having 1 Master Server and client on VMware (ESXi).

We successfully backup with standard of the policy type target are logs files.

but We are getting error code 84 when backup with Flashbackup-Windows of the policy type.

 

We have clenaed tape drives to to elimate this error still error persist.

Kindly find the below error logs. Could anyone please help me to resove this?

 

 

     As per the log "io_ioctl: ioctl (MTWEOF) failed on media id 870BBW, drive index 0, Incorrect function. (1) (bptm.c.22333) "

We have no idea. Can you share me any other solution for this?       

 

 

 

22:59:44.274 [4752.4816] <2> bptm: INITIATING (VERBOSE = 0): -w -pid 5264 -c gspclm06m -den 14 -rt 8 -rn 0 -stunit Tape-hcart2-robot-tld-0 -cl test_tape_06_cm_sys -bt 1337097573 -b gspclm06m_1337097573 -st 0 -cj 1 -p Daily -reqid -1337087160 -vm 2 -jm -brm -hostname gspclm06m -ru root -rclnt gspclm06m -rclnthostname gspclm06m -rl 24 -rp 2147483647 -sl full -ct 29 -maxfrag 1048576 -mediasvr GSPBKU010 -ifr -connect_options 0x01030202 -jobid 6649 -jobgrpid 6648 -masterversion 700000 -bpbrm_shm_id NetBackup_BPBRM_SHM_Path_10478406_5264_4600 -blks_per_buffer 128 -shm 
22:59:44.274 [4752.4816] <4> bptm: emmserver_name = GSPBKU010
22:59:44.274 [4752.4816] <4> bptm: emmserver_port = 1556
22:59:44.305 [4752.4816] <2> Orb::init: initializing ORB EMMlib_Orb with: dbstunitq -ORBSvcConfDirective "-ORBDottedDecimalAddresses 0" -ORBSvcConfDirective "static PBXIOP_Factory '-enable_keepalive'" -ORBSvcConfDirective "static EndpointSelectorFactory ''" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory PBXIOP_Factory'" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory IIOP_Factory'" -ORBSvcConfDirective "static PBXIOP_Evaluator_Factory '-orb EMMlib_Orb'" -ORBSvcConfDirective "static Resource_Factory '-ORBConnectionCacheMax 1024 '" -ORBSvcConf nul -ORBSvcConfDirective "static Server_Strategy_Factory '-ORBMaxRecvGIOPPayloadSize 268435456'"(Orb.cpp:741)
22:59:44.305 [4752.4816] <2> EndpointSelector::select_endpoint: performing call with the only endpt available!(Endpoint_Selector.cpp:437)
22:59:44.305 [4752.4816] <2> nbjm_media_request: Passing job control to NBJM, type WRITE/1
22:59:44.305 [4752.4816] <2> nbjm_media_request: old_media_id = , media_id = NULL
22:59:44.305 [4752.4816] <2> Orb::init: Created anon service name: NB_4752_-1092298273(Orb.cpp:612)
22:59:44.305 [4752.4816] <2> Orb::init: endpointvalue is : pbxiop://1556:NB_4752_-1092298273(Orb.cpp:630)
22:59:44.305 [4752.4816] <2> Orb::init: initializing ORB Default_DAEMON_Orb with: Unknown -ORBSvcConfDirective "-ORBDottedDecimalAddresses 0" -ORBSvcConfDirective "static PBXIOP_Factory '-enable_keepalive'" -ORBSvcConfDirective "static EndpointSelectorFactory ''" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory PBXIOP_Factory'" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory IIOP_Factory'" -ORBSvcConfDirective "static PBXIOP_Evaluator_Factory '-orb Default_DAEMON_Orb'" -ORBSvcConfDirective "static Resource_Factory '-ORBConnectionCacheMax 1024 '" -ORBEndpoint pbxiop://1556:NB_4752_-1092298273 -ORBSvcConf nul -ORBSvcConfDirective "static Server_Strategy_Factory '-ORBMaxRecvGIOPPayloadSize 268435456'"(Orb.cpp:741)
22:59:44.321 [4752.4816] <2> EndpointSelector::select_endpoint: performing call with the only endpt available!(Endpoint_Selector.cpp:437)
22:59:46.352 [4752.4816] <2> RequestInitialResources: returning
22:59:46.352 [4752.4816] <2> parse_resource_strings: MEDIADB 1 3634 870BBW 4000089 ------ 14 1337093537 1337093537 2147483647 0 0 0 0 24 9 0 0 1024 0 0 0
22:59:46.352 [4752.4816] <2> parse_resource_strings: Parsed message type 15, version 1, 21 parameters
22:59:46.352 [4752.4816] <2> parse_resource_strings: VOLUME 1 870BBW 4000089 870BBWL5 Daily FUJIFILM ACA4RE2PXP 14 8 0 19 0 {00000000-0000-0000-0000-000000000000} 0
22:59:46.352 [4752.4816] <2> parse_resource_strings: Parsed message type 16, version 1, 14 parameters
22:59:46.352 [4752.4816] <2> parse_resource_strings: DRIVE 3 IBM.ULT3580-TD5.000 2000008 1068041775 {3,0,0,0} -1 -1 -1 -1 -1 -1 -1 -1 *NULL* *NULL* *NULL* *NULL* 1 0 0 1 0 0
22:59:46.352 [4752.4816] <2> parse_resource_strings: Parsed message type 17, version 3, 23 parameters
22:59:46.352 [4752.4816] <2> parse_resource_strings: STORAGE 1 Tape-hcart2-robot-tld-0 14 1048576 2 1 0 0 GSPBKU010 GSPBKU010 *NULL*
22:59:46.352 [4752.4816] <2> parse_resource_strings: Parsed message type 18, version 1, 11 parameters
22:59:46.352 [4752.4816] <2> parse_resource_strings: DISKGROUP 0 6 *NULL* 6 *NULL* 6 *NULL*
22:59:46.352 [4752.4816] <2> parse_resource_strings: Parsed message type 21, version 0, 7 parameters
22:59:46.352 [4752.4816] <2> parse_resource_strings: DISKVOLUME 0 6 *NULL* 6 *NULL* 0
22:59:46.352 [4752.4816] <2> parse_resource_strings: Parsed message type 22, version 0, 6 parameters
22:59:46.352 [4752.4816] <2> parse_resource_strings: DISKMOUNTPOINT 0 6 *NULL*
22:59:46.352 [4752.4816] <2> parse_resource_strings: Parsed message type 23, version 0, 3 parameters
22:59:46.352 [4752.4816] <2> nbjm_media_request: Job control returned to BPTM
22:59:46.352 [4752.4816] <2> io_init: bpbrm_handle = 1028, buffer address = 0x15b0000
22:59:46.352 [4752.4816] <2> io_init: found index: 0
22:59:46.352 [4752.4816] <2> io_init: using 65536 data buffer size
22:59:46.352 [4752.4816] <2> io_init: CINDEX 0, sched Kbytes for monitoring = 60000
22:59:46.352 [4752.4816] <2> io_set_recvbuf: setting receive network buffer to 263168 bytes
22:59:46.352 [4752.4816] <2> io_init: using 30 data buffers
22:59:46.352 [4752.4816] <2> io_init: child delay = 10, parent delay = 15 (milliseconds)
22:59:46.352 [4752.4816] <2> create_shared_memory: shm_size = 1966808, buffer address = 0x0000000002AE0000, buf control = 0x0000000002CC0000, ready ptr = 0x0000000002CC02D0
22:59:46.352 [4752.4816] <2> setup_bpbkar_info: Global\NetBackup Media Manager SHM Info Path gspclm06m_1337097573 file successfully created
22:59:46.368 [4752.4816] <2> LOCAL CLASS_ATT_DEFS: Product ID = 6
22:59:46.368 [4752.4816] <4> report_client: VBRC 2 4752 1 gspclm06m_1337097573 29 test_tape_06_cm_sys 0 full 0 1 1 
22:59:46.368 [4752.4816] <2> ConnectionCache::connectAndCache: Acquiring new connection for host GSPBKU010, query type 81
22:59:46.430 [4752.4816] <2> vnet_vnetd_service_socket: ../../libvlibs/vnet_vnetd.c.2054: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006
22:59:46.430 [4752.4816] <2> vnet_vnetd_service_socket: ../../libvlibs/vnet_vnetd.c.2068: service: bpdbm
22:59:46.446 [4752.4816] <2> logconnections: BPDBM CONNECT FROM 127.0.0.1.51930 TO 127.0.0.1.13724
22:59:46.508 [4752.4816] <2> db_end: Need to collect reply
22:59:46.508 [4752.4816] <2> setup_back_header:  setup vm_type = 2
22:59:46.508 [4752.4816] <2> drivename_open: Called with Create 1, file IBM.ULT3580-TD5.000
22:59:46.508 [4752.4816] <2> drivename_checklock: Called
22:59:46.508 [4752.4816] <2> drivename_lock: lock established
22:59:46.508 [4752.4816] <2> drivename_write: Called with mode 0
22:59:46.508 [4752.4816] <2> drivename_unlock: unlocked
22:59:46.508 [4752.4816] <2> drivename_checklock: Called
22:59:46.508 [4752.4816] <2> drivename_lock: lock established
22:59:46.508 [4752.4816] <2> db_byid: search for media id 870BBW
22:59:46.524 [4752.4816] <2> send_MDS_msg: MEDIADB 1 3634 870BBW 4000089 *NULL* 14 1337097573 0 0 0 0 0 0 24 9 0 16 1024 0 0 0
22:59:46.540 [4752.4816] <2> bptm media_id_to_monitor: job_id = 6649
22:59:46.555 [4752.4816] <2> vnet_vnetd_service_socket: ../../libvlibs/vnet_vnetd.c.2054: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006
22:59:46.555 [4752.4816] <2> vnet_vnetd_service_socket: ../../libvlibs/vnet_vnetd.c.2068: service: bpjobd
22:59:46.555 [4752.4816] <2> job_connect: SO_KEEPALIVE set on socket 1036 for client GSPBKU010
22:59:46.555 [4752.4816] <2> logconnections: BPJOBD CONNECT FROM 127.0.0.1.51933 TO 127.0.0.1.13724
22:59:46.555 [4752.4816] <2> job_authenticate_connection: ignoring VxSS authentication check for now...
22:59:46.555 [4752.4816] <2> job_connect: Connected to the host GSPBKU010 contype 10 jobid <6649> socket <1036>
22:59:46.555 [4752.4816] <2> job_connect: Connected on port 51933
22:59:46.555 [4752.4816] <2> bptm media_id_to_monitor: pDstMediaId = 870BBW
22:59:46.555 [4752.4816] <2> mount_open_media: Waiting for mount of media id 870BBW (copy 1) on server GSPBKU010.
22:59:46.555 [4752.4816] <2> openNTDevice: config_path: {3,0,0,0}, serial_num: 1068041775
22:59:46.555 [4752.4816] <2> get_drive_path: SCSI coordinates {3,0,0,0}, dos_path \\.\Tape0, pnp_path \\?\scsi#sequential&ven_ibm&prod_ult3580-td5#5&3626375c&0&000000#{53f5630b-b6bf-11d0-94f2-00a0c91efb8b}
22:59:46.571 [4752.4816] <2> check_serial_num: serial number match for drive with SCSI coordinates {3,0,0,0}, dos_path \\.\Tape0, drive serial number 1068041775, expected serial number 1068041775
22:59:46.571 [4752.4816] <2> manage_scsi_reserve: retrying SCSI RESERVE (reserve unit scsi command failed, status = 0x2, key = 0x5, asc = 0x49, ascq = 0x0)
22:59:51.571 [4752.4816] <2> manage_scsi_reserve: retrying SCSI RESERVE (reserve unit scsi command failed, status = 0x2, key = 0x5, asc = 0x49, ascq = 0x0)
22:59:56.571 [4752.4816] <2> manage_scsi_reserve: retrying SCSI RESERVE (reserve unit scsi command failed, status = 0x2, key = 0x5, asc = 0x49, ascq = 0x0)
23:00:01.571 [4752.4816] <2> manage_scsi_reserve: retrying SCSI RESERVE (reserve unit scsi command failed, status = 0x2, key = 0x5, asc = 0x49, ascq = 0x0)
23:00:06.571 [4752.4816] <2> manage_scsi_reserve: retrying SCSI RESERVE (reserve unit scsi command failed, status = 0x2, key = 0x5, asc = 0x49, ascq = 0x0)
23:00:11.571 [4752.4816] <2> manage_scsi_reserve: retrying SCSI RESERVE (reserve unit scsi command failed, status = 0x2, key = 0x5, asc = 0x49, ascq = 0x0)
23:00:16.571 [4752.4816] <2> manage_scsi_reserve: retrying SCSI RESERVE (reserve unit scsi command failed, status = 0x2, key = 0x5, asc = 0x49, ascq = 0x0)
23:00:21.571 [4752.4816] <2> manage_scsi_reserve: retrying SCSI RESERVE (reserve unit scsi command failed, status = 0x2, key = 0x5, asc = 0x49, ascq = 0x0)
23:00:26.571 [4752.4816] <2> manage_scsi_reserve: SCSI RESERVE failed (reserve unit scsi command failed, status = 0x2, key = 0x5, asc = 0x49, ascq = 0x0)
23:00:26.571 [4752.4816] <2> send_MDS_msg: OP_STATUS 0 3634 GSPBKU010 8196 5 0 0 0 0 0 0 *NULL* 0
23:00:26.571 [4752.4816] <2> send_operation_error: Decoded status = 4 from 5
23:00:26.571 [4752.4816] <2> log_media_error: successfully wrote to error file - 05/15/12 23:00:26 870BBW -1 RESERVE_ERROR IBM.ULT3580-TD5.000 0 1 0 0
23:00:26.774 [4752.4816] <2> tapelib: wait_for_ltid, Mount, timeout 0
23:04:28.274 [4752.4816] <2> openTpreqFile: tpreq_file: C:\Program Files\Veritas\NetBackup\db\media\tpreq\drive_IBM.ULT3580-TD5.000, serial_num: 1068041775
23:04:28.274 [4752.4816] <2> get_drive_path: SCSI coordinates {3,0,0,0}, dos_path \\.\Tape0, pnp_path \\?\scsi#sequential&ven_ibm&prod_ult3580-td5#5&3626375c&0&000000#{53f5630b-b6bf-11d0-94f2-00a0c91efb8b}
23:04:28.274 [4752.4816] <2> check_serial_num: serial number match for drive with SCSI coordinates {3,0,0,0}, dos_path \\.\Tape0, drive serial number 1068041775, expected serial number 1068041775
23:04:28.274 [4752.4816] <2> init_tape: \\.\Tape0 (SCSI coordinates {3,0,0,0}) configured with blocksize 0
23:04:28.290 [4752.4816] <2> init_tape: \\.\Tape0 (SCSI coordinates {3,0,0,0}) has compression enabled
23:04:28.290 [4752.4816] <2> io_open: SCSI RESERVE
23:04:28.305 [4752.4816] <2> manage_drive_attributes: report_attr, fl1 0x00000049, fl2 0x0000000c
23:04:28.305 [4752.4816] <2> manage_drive_attributes: Matching medium manufacturer [FUJIFILM], sn [ACA4RE2PXP]
23:04:28.305 [4752.4816] <2> manage_drive_attributes: Host Attributes: Vendor [SYMANTEC], Name [NetBackup BPTM], Version [7.0]
23:04:28.305 [4752.4816] <2> manage_drive_attributes: DateLabeled [201202291200], Barcode [870BBWL5], Owninghost [GSPBKU010]
23:04:28.305 [4752.4816] <2> manage_drive_attributes: MediaPool [Database], MediaLabel [MEDIA=870BBW;]
23:04:28.305 [4752.4816] <2> io_open: file C:\Program Files\Veritas\NetBackup\db\media\tpreq\drive_IBM.ULT3580-TD5.000 successfully opened (mode 2)
23:04:28.305 [4752.4816] <2> write_backup: media id 870BBW mounted on drive index 0, drivepath {3,0,0,0}, drivename IBM.ULT3580-TD5.000, copy 1
23:04:28.305 [4752.4816] <4> report_throughput: VBRT 1 4752 1 1 IBM.ULT3580-TD5.000 870BBW 0 1 0 0  0 (bptm.c.20536)
23:04:28.305 [4752.4816] <2> io_read_media_header: drive index 0, reading media header, buflen = 65536, buff = 0x000000000275DCD0, copy 1
23:04:28.305 [4752.4816] <2> io_ioctl: command (5)MTREW 1 from (bptm.c.8183) on drive index 0
23:04:28.321 [4752.4816] <2> io_write_media_header: drive index 0, writing media header
23:04:28.321 [4752.4816] <2> io_ioctl: command (5)MTREW 1 from (bptm.c.9495) on drive index 0
23:04:28.415 [4752.4816] <2> io_ioctl: command (0)MTWEOF 1 from (bptm.c.9536) on drive index 0
23:04:31.758 [4752.4816] <2> io_write_media_header: report_density, 0x58 [LTO-CVE U-516]
23:04:31.758 [4752.4816] <2> send_MDS_msg: VOL_UPDATE 0 3634 870BBW 4000089 FUJIFILM ACA4RE2PXP 4 88
23:04:31.774 [4752.4816] <2> send_MDS_msg: MEDIADB 1 3634 870BBW 4000089 *NULL* 14 1337097573 1337097573 2147483647 0 0 0 0 24 9 0 0 1024 0 0 0
23:04:31.774 [4752.4816] <4> write_backup: begin writing backup id gspclm06m_1337097573, copy 1, fragment 1, to media id 870BBW on drive IBM.ULT3580-TD5.000 (index 0)
23:04:31.774 [4752.4816] <2> signal_parent: set bpbrm media ready event (pid = 5264)
23:04:31.774 [4752.4816] <2> write_data: twin_index: 0 active: 1 dont_process: 0 wrote_backup_hdr: 0 finished_buff: 0 saved_cindex: -1 twin_is_disk 0 delay_brm: 0
23:04:31.774 [4752.4816] <2> write_data: Total Kbytes transferred 0
23:04:31.774 [4752.4816] <2> write_data: absolute block position prior to writing backup header(s) is 2, copy 1
23:04:31.774 [4752.4816] <2> io_write_back_header: drive index 0, gspclm06m_1337097573, file num = 1, mpx_headers = 0, copy 1
23:04:31.774 [4752.4816] <2> write_data: completed writing backup header, start writing data when first buffer is available, copy 1
23:04:31.774 [4752.4816] <2> write_data: first write, twin_index: 0 cindex: 0 dont_process: 1 wrote_backup_hdr: 1 finished_buff: 0
23:04:31.774 [4752.4816] <2> write_data: received first buffer (65536 bytes), begin writing data
23:05:38.415 [4660.956] <2> bptm: INITIATING (VERBOSE = 0): -rptdrv -jobid -1337087161 -jm 
23:05:38.415 [4660.956] <2> drivename_open: Called with Create 0, file IBM.ULT3580-TD5.000
23:05:38.415 [4660.956] <2> drivename_checklock: Called
23:05:38.415 [4660.956] <2> drivename_checklock: File is locked
23:05:38.415 [4660.956] <2> report_drives: DRIVE = IBM.ULT3580-TD5.000 LOCK = TRUE CURTIME = 1337097938
23:05:38.415 [4660.956] <2> drivename_close: Called for file IBM.ULT3580-TD5.000
23:05:38.415 [4660.956] <2> report_drives: MODE = 0
23:05:38.415 [4660.956] <2> report_drives: TIME = 1337097586
23:05:38.415 [4660.956] <2> report_drives: MASTER = GSPBKU010
23:05:38.415 [4660.956] <2> report_drives: SR_KEY = 0 1
23:05:38.415 [4660.956] <2> report_drives: PATH = {3,0,0,0}
23:05:38.415 [4660.956] <2> report_drives: MEDIA = 870BBW
23:05:38.415 [4660.956] <2> report_drives: REQID = -1337087160
23:05:38.415 [4660.956] <2> report_drives: ALOCID = 3634
23:05:38.415 [4660.956] <2> report_drives: RBID = {7E56C2E7-84FB-4293-976A-8C7A187337F4}
23:05:38.415 [4660.956] <2> report_drives: PID = 4752
23:05:38.415 [4660.956] <2> report_drives: FILE = C:\Program Files\Veritas\NetBackup\db\media\tpreq\drive_IBM.ULT3580-TD5.000
23:05:38.415 [4660.956] <2> main: Sending [EXIT STATUS 0] to NBJM
23:05:38.415 [4660.956] <2> bptm: EXITING with status 0 <----------
23:06:49.211 [4752.4816] <2> write_data: writing block shorter than BUFF_SIZE, 60416 bytes
23:06:49.211 [4752.4816] <2> write_data: writing short block, 60416 bytes, remainder 0
23:06:49.211 [4752.4816] <2> write_data: waited for full buffer 5002 times, delayed 8576 times
23:06:49.211 [4752.4816] <2> write_data: Total Kbytes transferred 9242299
23:06:49.211 [4752.4816] <2> write_backup: write_data() returned, exit_status = 0, CINDEX = 0, TWIN_INDEX = 0, backup_status = 0
23:06:49.211 [4752.4816] <2> io_ioctl: command (0)MTWEOF 1 from (bptm.c.22333) on drive index 0
23:06:49.211 [4752.4816] <16> io_ioctl: ioctl (MTWEOF) failed on media id 870BBW, drive index 0, Incorrect function. (1) (bptm.c.22333)
23:06:49.211 [4752.4816] <2> send_MDS_msg: DEVICE_STATUS 1 3634 GSPBKU010 870BBW 4000089 IBM.ULT3580-TD5.000 2000008 WRITE_ERROR 0 0
23:06:49.227 [4752.4816] <2> log_media_error: successfully wrote to error file - 05/15/12 23:06:49 870BBW 0 WRITE_ERROR IBM.ULT3580-TD5.000
23:06:49.227 [4752.4816] <2> check_error_history: just tpunmount: called from bptm line 22357, EXIT_Status = 84
23:06:49.227 [4752.4816] <2> io_close: closing C:\Program Files\Veritas\NetBackup\db\media\tpreq\drive_IBM.ULT3580-TD5.000, from bptm.c.18975
23:06:49.227 [4752.4816] <2> drivename_write: Called with mode 1
23:06:49.227 [4752.4816] <2> drivename_unlock: unlocked
23:06:49.227 [4752.4816] <2> drivename_checklock: Called
23:06:49.227 [4752.4816] <2> drivename_lock: lock established
23:06:49.227 [4752.4816] <2> drivename_unlock: unlocked
23:06:49.227 [4752.4816] <2> drivename_close: Called for file IBM.ULT3580-TD5.000
23:06:49.227 [4752.4816] <2> tpunmount: NOP: MEDIA_DONE 0 6649 0 870BBW 4000089 0 {7E56C2E7-84FB-4293-976A-8C7A187337F4}
23:06:49.227 [4752.4816] <2> bptm: EXITING with status 84 <----------
23:06:52.227 [4752.4816] <2> cleanup: Detached from BPBRM shared memory
23:06:54.461 [4572.4100] <2> bptm: INITIATING (VERBOSE = 0): -unload -dn IBM.ULT3580-TD5.000 -dp {3,0,0,0} -dk 2000008 -m 870BBW -mk 4000089 -mds 0 -alocid 3634 -jobid -1337087162 -jm 
23:06:54.461 [4572.4100] <4> bptm: emmserver_name = GSPBKU010
23:06:54.461 [4572.4100] <4> bptm: emmserver_port = 1556
23:06:54.477 [4572.4100] <2> Orb::init: initializing ORB EMMlib_Orb with: dbstunitq -ORBSvcConfDirective "-ORBDottedDecimalAddresses 0" -ORBSvcConfDirective "static PBXIOP_Factory '-enable_keepalive'" -ORBSvcConfDirective "static EndpointSelectorFactory ''" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory PBXIOP_Factory'" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory IIOP_Factory'" -ORBSvcConfDirective "static PBXIOP_Evaluator_Factory '-orb EMMlib_Orb'" -ORBSvcConfDirective "static Resource_Factory '-ORBConnectionCacheMax 1024 '" -ORBSvcConf nul -ORBSvcConfDirective "static Server_Strategy_Factory '-ORBMaxRecvGIOPPayloadSize 268435456'"(Orb.cpp:741)
23:06:54.493 [4572.4100] <2> EndpointSelector::select_endpoint: performing call with the only endpt available!(Endpoint_Selector.cpp:437)
23:06:54.493 [4572.4100] <2> send_brm_msg: PID of bpxm = 4572
23:06:54.493 [4572.4100] <2> nbjm_media_request: Passing job control to NBJM, type UNLOAD/6
23:06:54.493 [4572.4100] <2> nbjm_media_request: old_media_id = NULL, media_id = 870BBW
23:06:54.493 [4572.4100] <2> Orb::init: Created anon service name: NB_4572_447556087(Orb.cpp:612)
23:06:54.493 [4572.4100] <2> Orb::init: endpointvalue is : pbxiop://1556:NB_4572_447556087(Orb.cpp:630)
23:06:54.493 [4572.4100] <2> Orb::init: initializing ORB Default_DAEMON_Orb with: Unknown -ORBSvcConfDirective "-ORBDottedDecimalAddresses 0" -ORBSvcConfDirective "static PBXIOP_Factory '-enable_keepalive'" -ORBSvcConfDirective "static EndpointSelectorFactory ''" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory PBXIOP_Factory'" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory IIOP_Factory'" -ORBSvcConfDirective "static PBXIOP_Evaluator_Factory '-orb Default_DAEMON_Orb'" -ORBSvcConfDirective "static Resource_Factory '-ORBConnectionCacheMax 1024 '" -ORBEndpoint pbxiop://1556:NB_4572_447556087 -ORBSvcConf nul -ORBSvcConfDirective "static Server_Strategy_Factory '-ORBMaxRecvGIOPPayloadSize 268435456'"(Orb.cpp:741)
23:06:54.493 [4572.4100] <2> EndpointSelector::select_endpoint: performing call with the only endpt available!(Endpoint_Selector.cpp:437)
23:06:56.508 [4572.4100] <2> RequestInitialResources: returning
23:06:56.508 [4572.4100] <2> parse_resource_strings: MEDIADB 1 3634 870BBW 4000089 ------ 14 1337093537 1337093537 2147483647 0 0 0 0 24 9 0 0 1024 0 0 0
23:06:56.508 [4572.4100] <2> parse_resource_strings: Parsed message type 15, version 1, 21 parameters
23:06:56.508 [4572.4100] <2> parse_resource_strings: VOLUME 1 870BBW 4000089 870BBWL5 Daily FUJIFILM ACA4RE2PXP 14 8 0 19 0 {00000000-0000-0000-0000-000000000000} 0
23:06:56.508 [4572.4100] <2> parse_resource_strings: Parsed message type 16, version 1, 14 parameters
23:06:56.508 [4572.4100] <2> parse_resource_strings: DRIVE 3 IBM.ULT3580-TD5.000 2000008 1068041775 {3,0,0,0} -1 -1 -1 -1 -1 -1 -1 -1 *NULL* *NULL* *NULL* *NULL* 1 0 0 1 0 0
23:06:56.508 [4572.4100] <2> parse_resource_strings: Parsed message type 17, version 3, 23 parameters
23:06:56.508 [4572.4100] <2> parse_resource_strings: STORAGE 1 Tape-hcart2-robot-tld-0 14 1048576 2 1 0 0 GSPBKU010 GSPBKU010 *NULL*
23:06:56.508 [4572.4100] <2> parse_resource_strings: Parsed message type 18, version 1, 11 parameters
23:06:56.508 [4572.4100] <2> parse_resource_strings: DISKGROUP 0 6 *NULL* 6 *NULL* 6 *NULL*
23:06:56.508 [4572.4100] <2> parse_resource_strings: Parsed message type 21, version 0, 7 parameters
23:06:56.508 [4572.4100] <2> parse_resource_strings: DISKVOLUME 0 6 *NULL* 6 *NULL* 0
23:06:56.508 [4572.4100] <2> parse_resource_strings: Parsed message type 22, version 0, 6 parameters
23:06:56.508 [4572.4100] <2> parse_resource_strings: DISKMOUNTPOINT 0 6 *NULL*
23:06:56.508 [4572.4100] <2> parse_resource_strings: Parsed message type 23, version 0, 3 parameters
23:06:56.508 [4572.4100] <2> nbjm_media_request: Job control returned to BPTM
23:06:56.508 [4572.4100] <2> drivename_open: Called with Create 1, file IBM.ULT3580-TD5.000
23:06:56.508 [4572.4100] <2> drivename_checklock: Called
23:06:56.508 [4572.4100] <2> drivename_lock: lock established
23:06:56.508 [4572.4100] <4> create_tpreq_file: {3,0,0,0}
23:06:56.508 [4572.4100] <2> drivename_write: Called with mode 2
23:06:56.508 [4572.4100] <2> drivename_unlock: unlocked
23:06:56.508 [4572.4100] <2> drivename_checklock: Called
23:06:56.508 [4572.4100] <2> drivename_lock: lock established
23:06:56.508 [4572.4100] <2> openNTDevice: config_path: {3,0,0,0}, serial_num: 1068041775
23:06:56.508 [4572.4100] <2> get_drive_path: SCSI coordinates {3,0,0,0}, dos_path \\.\Tape0, pnp_path \\?\scsi#sequential&ven_ibm&prod_ult3580-td5#5&3626375c&0&000000#{53f5630b-b6bf-11d0-94f2-00a0c91efb8b}
23:06:56.508 [4572.4100] <2> check_serial_num: serial number match for drive with SCSI coordinates {3,0,0,0}, dos_path \\.\Tape0, drive serial number 1068041775, expected serial number 1068041775
23:06:57.149 [4572.4100] <2> process_tapealert: TapeAlert returned 0x00000000 0x00000000 (from tapealert_and_release)
23:06:57.149 [4572.4100] <2> really_tpunmount: tpunmount'ing C:\Program Files\Veritas\NetBackup\db\media\tpreq\drive_IBM.ULT3580-TD5.000
23:06:57.961 [4572.4100] <2> tapelib: wait_for_ltid, UnMount, timeout 2400
23:07:04.649 [5124.4160] <2> bptm: INITIATING (VERBOSE = 0): -delete_all_expired 
23:07:04.649 [5124.4160] <4> bptm: emmserver_name = GSPBKU010
23:07:04.649 [5124.4160] <4> bptm: emmserver_port = 1556
23:07:04.665 [5124.4160] <2> Orb::init: initializing ORB EMMlib_Orb with: dbstunitq -ORBSvcConfDirective "-ORBDottedDecimalAddresses 0" -ORBSvcConfDirective "static PBXIOP_Factory '-enable_keepalive'" -ORBSvcConfDirective "static EndpointSelectorFactory ''" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory PBXIOP_Factory'" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory IIOP_Factory'" -ORBSvcConfDirective "static PBXIOP_Evaluator_Factory '-orb EMMlib_Orb'" -ORBSvcConfDirective "static Resource_Factory '-ORBConnectionCacheMax 1024 '" -ORBSvcConf nul -ORBSvcConfDirective "static Server_Strategy_Factory '-ORBMaxRecvGIOPPayloadSize 268435456'"(Orb.cpp:741)
23:07:04.665 [5124.4160] <2> EndpointSelector::select_endpoint: performing call with the only endpt available!(Endpoint_Selector.cpp:437)
23:07:04.696 [5124.4160] <2> bptm: EXITING with status 0 <----------

 

0 Kudos
7 REPLIES 7

Marianne
Level 6
Partner    VIP    Accredited Certified

‎05-15-2012 04:28 AM

You have Master server on VMware?

Master Server and client on VMware (ESXi).

If my understanding is correct (that your Master/media server is a VM)  - Virtual Media server using tape is NOT supported.

Only disk storage is supported on Virtual media server.

See NB_7x_virtualization_Feb24_12_update http://www.symantec.com/docs/TECH127089

You need a physical server to use tape storage.

 


Handy NetBackup Links
0 Kudos

muhanad_daher
Level 6
Partner Accredited Certified

‎05-15-2012 05:56 AM

are you use a VTL storage?

is backup success for any other servers (non-vmware)?

0 Kudos

pstandpon
Level 2

‎05-15-2012 07:49 PM

Hi,

    Sorry for response late.

- My Master/Media server is a VM (windows2008 r2 64bit, NB7.0)

- The client is Redhat 5 and it is VM

- We are can backup with the log file by using tape BUT Not with Flashbackup-Windows of the policy type. (We have a picture about this)

- Why successfully with the log?

- NB is version 7.0.

- We use a tape library (TS3100) and LTO

Sorry for my language if you don't understand me but i have try to explain.

Thanks you very much.

0 Kudos

Marianne
Level 6
Partner    VIP    Accredited Certified

‎05-15-2012 09:11 PM

Anything working in this environment is pure luck.

Physical tape is NOT supported on VM.

NBU is REPORTING these errors (not causing it):

22:59:46.571 [4752.4816] <2> manage_scsi_reserve: retrying SCSI RESERVE (reserve unit scsi command failed, status = 0x2, key = 0x5, asc = 0x49, ascq = 0x0)
22:59:51.571 [4752.4816] <2> manage_scsi_reserve: retrying SCSI RESERVE (reserve unit scsi command failed, status = 0x2, key = 0x5, asc = 0x49, ascq = 0x0)
22:59:56.571 [4752.4816] <2> manage_scsi_reserve: retrying SCSI RESERVE (reserve unit scsi command failed, status = 0x2, key = 0x5, asc = 0x49, ascq = 0x0)
23:00:01.571 [4752.4816] <2> manage_scsi_reserve: retrying SCSI RESERVE (reserve unit scsi command failed, status = 0x2, key = 0x5, asc = 0x49, ascq = 0x0)
23:00:06.571 [4752.4816] <2> manage_scsi_reserve: retrying SCSI RESERVE (reserve unit scsi command failed, status = 0x2, key = 0x5, asc = 0x49, ascq = 0x0)
23:00:11.571 [4752.4816] <2> manage_scsi_reserve: retrying SCSI RESERVE (reserve unit scsi command failed, status = 0x2, key = 0x5, asc = 0x49, ascq = 0x0)
.......
23:06:49.211 [4752.4816] <16> io_ioctl: ioctl (MTWEOF) failed on media id 870BBW, drive index 0, Incorrect function. (1) (bptm.c.22333)
 
The 'MTWEOF' (Write end of File) error is described in this TN: http://www.symantec.com/docs/TECH59178

In answer to your question:

Can you share me any other solution for this?

Yes. Move Master/Media server and tape library to a physical server.

 


Handy NetBackup Links
0 Kudos

muhanad_daher
Level 6
Partner Accredited Certified

‎05-15-2012 10:18 PM

How long time you use master on VMware?

0 Kudos

pstandpon
Level 2

‎05-15-2012 11:43 PM

Hi,

I just to migration my environment from Physical server(NBU) to VM 2Weeks ago.

Because we have a plan for install NetBackup and BackupExec into same a physical server

We have two project (NBU and BE) but have one Tape Library and one Physical server and one Blade Server for client.

Are you confirm about BE that same problem?

Or Any idea?

Big thank

0 Kudos

muhanad_daher
Level 6
Partner Accredited Certified

‎05-15-2012 11:49 PM

of course BE will have same issue, but why to use nbu and BE in the same DC and for one library;

this illogical to use both NBU and BE on same library;

my question: how you want to connect both application on storage physically with the same time?

0 Kudos