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

Problems with flashbackup Windows

CarlosRmz
Not applicable
Partner Accredited

‎06-06-2011 09:05 AM

Hi.

I have a problem when try to backup a one drive "Y" on Windows 2003 Cluster.

Dont have eny error status, the robot mount the tape but they stay only "begin write"

I have master server 6.5.5 end client 6.5.5

Only have this messages on bpkar log:

 

11:41:40.370 AM: [6644.7508] <2> WinMain: DAT - _pgmptr = 'C:\Program Files\Veritas\NetBackup\bin\bpbkar32.exe'
11:41:40.370 AM: [6644.7508] <2> WinMain: DAT - lpCmdLine = '-r 604800 -ru root -dt 0 -to 0 -clnt TNWP02662 -class PruebaY -sched Full_Semanal -st FULL -bpstart_to 300 -bpend_to 300 -read_to 300 -stream_count 1 -stream_number 1 -jobgrpid 97033 -blks_per_buffer 128 -use_otm -fso -ifr -pid 3088 -mediasvr TNWP02662 -bt 1306773682 -t 0 -b TNWP02662_1306773682 -kl 28 -fi -S tnwp02981 -fim NONE -ct 29 -use_ofb '
11:41:40.370 AM: [6644.7508] <2> date_debug: DAT - timezone: Central Standard Time (Mexico), offset=21600, dst: Central Daylight Time (Mexico)
11:41:40.370 AM: [6644.7508] <2> date_debug: DAT - current time: 1306773700, 5/30/2011 11:41:40 AM
11:41:40.370 AM: [6644.7508] <2> date_debug: DAT - 01/01/94 UCT:  757382400, 12/31/1993 7:00:00 PM
11:41:40.370 AM: [6644.7508] <2> date_debug: DAT - 07/01/94 UCT:  773020800, 6/30/1994 7:00:00 PM
11:41:40.370 AM: [6644.7508] <2> WinMain: DAT - standard input handle = 516
11:41:40.370 AM: [6644.7508] <2> WinMain: DAT - standard output handle = 1916
11:41:40.370 AM: [6644.7508] <2> WinMain: DAT - standard error handle = 1856
11:41:40.574 AM: [6644.7508] <8> tar_process_backup_args: WRN - invalid option: -pid 3088
11:41:40.574 AM: [6644.7508] <8> tar_process_backup_args: WRN - invalid option: -mediasvr TNWP02662
11:41:40.574 AM: [6644.7508] <8> tar_process_backup_args: WRN - invalid option: -bt 1306773682
11:41:40.574 AM: [6644.7508] <8> tar_process_backup_args: WRN - invalid option: -t 0
11:41:40.574 AM: [6644.7508] <4> tar_backup::V_SetupJobData: INF - dwJobData: ffffffff
11:41:40.574 AM: [6644.7508] <4> tar_backup::V_SetupJobData: INF -     dwJob: ffffffff
11:41:40.855 AM: [6644.7508] <4> dos_backup::V_PreProcessing: INF - backup privileges enabled, previous = 0
11:41:40.855 AM: [6644.7508] <4> dos_backup::V_PreProcessing: INF - restore privileges enabled, previous = 0
11:41:40.855 AM: [6644.7508] <4> dos_backup::V_PreProcessing: INF - security privileges enabled, previous = 0
11:41:40.855 AM: [6644.7508] <4> dos_backup::V_PreProcessing: INF - tcb privileges enabled, previous = 0
11:41:40.855 AM: [6644.7508] <4> dos_backup::V_PreProcessing: INF - create token privileges enabled, previous = 0
11:41:40.855 AM: [6644.7508] <4> dos_backup::V_PreProcessing: INF - user name: root
11:41:40.855 AM: [6644.7508] <4> dos_backup::V_PreProcessing: INF - no access token: scheduled backup
11:41:40.902 AM: [6644.7508] <2> ov_log::V_GlobalLog: INF - BEDS_Init() Enter InitFlags:0x0
11:42:04.622 AM: [6644.7508] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: C:
11:42:04.622 AM: [6644.7508] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: D:
11:42:04.622 AM: [6644.7508] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: K:
11:42:04.622 AM: [6644.7508] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: U:
11:42:04.622 AM: [6644.7508] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: W:
11:42:04.622 AM: [6644.7508] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Y:
11:42:04.622 AM: [6644.7508] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Microsoft Terminal Services
11:42:04.622 AM: [6644.7508] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Microsoft Windows Network
11:42:04.622 AM: [6644.7508] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: NFS Network
11:42:04.622 AM: [6644.7508] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Web Client Network
11:42:04.622 AM: [6644.7508] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: TNWP02662\MS_ADMT
11:42:04.637 AM: [6644.7508] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Shadow?Copy?Components
11:42:04.637 AM: [6644.7508] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: System?State
11:42:04.637 AM: [6644.7508] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Active Directory Application Mode
11:42:05.059 AM: [6644.7508] <2> tar_backup::V_SetupProcessContinue: TAR - CONTINUE BACKUP received
11:42:05.059 AM: [6644.7508] <2> tar_backup_tfi::setupFileDirectives: TAR - backup filename = BACKUP \\.\y:\ USING \\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy12\ OPTIONS:ALT_PATH_PREFIX=C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_3036,FITYPE=MIRROR,MNTPOINT=\\.\y:\,FSTYPE=NTFS
11:42:05.075 AM: [6644.6188] <4> tar_base::keepaliveThread: INF - keepalive thread is active with an interval of 60 seconds
11:42:05.091 AM: [6644.7508] <2> tar_backup_vxbsa::add: INF - called with 'BACKUP \\.\y:\ USING \\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy12\ OPTIONS:ALT_PATH_PREFIX=C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_3036,FITYPE=MIRROR,MNTPOINT=\\.\y:\,FSTYPE=NTFS'
11:42:05.122 AM: [6644.7508] <4> V_Snapshot::V_Snapshot_AddMappings: INF - Volume Snapshot Mapping: \\?\Volume{91217c0d-fcc4-11da-88c1-00508bdf5e60}\ --> GLOBALROOT\Device\HarddiskVolumeShadowCopy12
11:42:05.122 AM: [6644.7508] <4> dos_backup::V_VerifyFileList: INF - UBS Local Type for '\\.\y:\' --> 00000000
11:42:09.247 AM: [6644.7508] <4> backup_create: INF - NetBackup Temp Directory: 'C:\Program Files\Veritas\\NetBackup\Temp'
11:42:09.263 AM: [6644.7508] <4> dos_backup::tfs_startraw: INF - checking for: Y:\
11:42:09.263 AM: [6644.7508] <4> V_DetermineMountInfo: INF - Checking Volume \\?\Volume{91217c0d-fcc4-11da-88c1-00508bdf5e60}\ for '\'
11:42:09.263 AM: [6644.7508] <4> V_DetermineMountInfo: INF -   Adding NTFS Volume \\.\y: ==> \\?\Volume{91217c0d-fcc4-11da-88c1-00508bdf5e60}\
11:42:09.263 AM: [6644.7508] <4> dos_backup::tfs_startraw: INF - RAW: \\.\
11:42:09.263 AM: [6644.7508] <4> dos_backup::tfs_startraw: INF - RAW: y:
11:42:09.263 AM: [6644.7508] <4> tar_backup_tfi::flash_start_state: FlashBackup of Raw partition
11:42:09.263 AM: [6644.7508] <4> tar_backup_tfi::flash_start_state: FlashBackup of Raw partition isFlashBackup 0
11:42:09.591 AM: [6644.7508] <2> tar_base::V_vTarMsgW: INF - FIML startup time = 0
12:08:55.664 PM: [6644.7508] <2> tar_base::V_vTarMsgW: INF - arg list: rawdisk Y:\ inctime 0 Startblk 0
12:08:55.664 PM: [6644.7508] <16> dtcp_write: TCP - failure: send socket (1856) (TCP 10054: Connection reset by peer)
12:08:55.664 PM: [6644.7508] <16> dtcp_write: TCP - failure: attempted to send 49 bytes
12:08:55.664 PM: [6644.7508] <2> tar_base::V_vTarMsgW: INF -      clientname TNWP02662, buid TNWP02662_1306773682, filnum 0
12:08:55.664 PM: [6644.7508] <16> dtcp_write: TCP - failure: send socket (1856) (TCP 10054: Connection reset by peer)
12:08:55.679 PM: [6644.7508] <16> dtcp_write: TCP - failure: attempted to send 66 bytes
12:08:55.679 PM: [6644.7508] <2> tar_base::V_vTarMsgW: INF - iDataLength == 0

 

bptm

11:42:54.624 [7992.7092] <2> Orb::init: initializing ORB Default_CLIENT_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 Default_CLIENT_Orb'" -ORBSvcConfDirective "static Resource_Factory '-ORBConnectionCacheMax 1024 '" -ORBSvcConf nul -ORBSvcConfDirective "static Server_Strategy_Factory '-ORBMaxRecvGIOPPayloadSize 268435456'"(Orb.cpp:720)
11:42:54.624 [7992.7092] <2> EndpointSelector::select_endpoint: performing call with the only endpt available!(Endpoint_Selector.cpp:431)
11:42:55.015 [2748.5572] <2> tapelib: wait_for_ltid, Mount, timeout 0
11:42:59.015 [2748.5572] <2> send_MDS_msg: OP_STATUS 0 22542 tnwp02662 8 1 0 0 0 0 0 0 *NULL* 0
11:42:59.031 [2748.5572] <2> send_operation_error: Decoded status = 8 from 1
11:42:59.062 [2748.5572] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2048: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006
11:42:59.062 [2748.5572] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2062: service: bpdbm
11:42:59.171 [2748.5572] <2> logconnections: BPDBM CONNECT FROM 3.84.57.54.1293 TO 3.84.57.52.13724
11:42:59.250 [7992.7092] <2> setup_mm_child: [2748] child using 200 data buffers
11:42:59.250 [7992.7092] <2> setup_mm_child: [2748] child buffer size is 65536
11:42:59.250 [7992.7092] <2> setup_mm_child: [2748] buffer address = 0x1390000, buf control = 0x2010000
11:43:04.156 [2748.5572] <16> mount_open_media: error requesting media, TpErrno = Robot operation failed
11:43:04.156 [2748.5572] <2> openNTDevice: config_path: {3,0,0,2}, serial_num: PMC39Y0728
11:43:04.156 [2748.5572] <2> get_drive_path: SCSI coordinates {3,0,0,2}, dos_path \\.\Tape1, pnp_path \\?\scsi#sequential&ven_compaq&prod_sdlt320&rev_5f5f#5&23ce36ff&0&000002#{53f5630b-b6bf-11d0-94f2-00a0c91efb8b}
11:43:04.156 [2748.5572] <2> check_serial_num: serial number match for drive with SCSI coordinates {3,0,0,2}, dos_path \\.\Tape1, drive serial number PMC39Y0728, expected serial number PMC39Y0728
11:43:04.156 [2748.5572] <2> manage_scsi_reserve: SCSI RELEASE
11:43:04.156 [2748.5572] <2> drivename_write: Called with mode 1
11:43:04.156 [2748.5572] <2> drivename_unlock: unlocked
11:43:04.156 [2748.5572] <2> drivename_checklock: Called
11:43:04.156 [2748.5572] <2> drivename_lock: lock established
11:43:04.156 [2748.5572] <2> drivename_unlock: unlocked
11:43:04.156 [2748.5572] <2> drivename_close: Called for file COMPAQ.SDLT320.001
11:43:04.172 [2748.5572] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2048: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006
11:43:04.172 [2748.5572] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2062: service: bpdbm
11:43:04.359 [2748.5572] <2> logconnections: BPDBM CONNECT FROM 3.84.57.54.1315 TO 3.84.57.52.13724
11:43:09.234 [2748.5572] <8> write_backup: media id PV018N load operation reported an error
11:43:09.234 [2748.5572] <2> nbjm_media_request: Passing job control to NBJM, type FAIL/1
11:43:09.234 [2748.5572] <2> nbjm_media_request: old_media_id = PV018N, media_id = NULL
11:43:10.500 [4888.4528] <2> bptm: INITIATING (VERBOSE = 0): -unload -dn COMPAQ.SDLT320.001 -dp {3,0,0,2} -dk 2000101 -m PV018N -mk 4001713 -mds 0 -alocid 22542 -jobid -1306721454 -jm
11:43:10.500 [4888.4528] <4> bptm: emmserver_name = tnwp02981
11:43:10.500 [4888.4528] <4> bptm: emmserver_port = 1556
11:43:10.516 [4888.4528] <2> Orb::init: initializing ORB Default_CLIENT_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 Default_CLIENT_Orb'" -ORBSvcConfDirective "static Resource_Factory '-ORBConnectionCacheMax 1024 '" -ORBSvcConf nul -ORBSvcConfDirective "static Server_Strategy_Factory '-ORBMaxRecvGIOPPayloadSize 268435456'"(Orb.cpp:720)
11:43:10.531 [4888.4528] <2> EndpointSelector::select_endpoint: performing call with the only endpt available!(Endpoint_Selector.cpp:431)
11:43:19.563 [4888.4528] <2> send_brm_msg: PID of bpxm = 4888
11:43:19.563 [4888.4528] <2> nbjm_media_request: Passing job control to NBJM, type UNLOAD/6
11:43:19.563 [4888.4528] <2> nbjm_media_request: old_media_id = NULL, media_id = PV018N
11:43:19.563 [4888.4528] <2> Orb::init: Created anon service name: NB_4888_878830115(Orb.cpp:591)
11:43:19.563 [4888.4528] <2> Orb::init: endpointvalue is : pbxiop://1556:NB_4888_878830115(Orb.cpp:609)
11:43:19.563 [4888.4528] <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_4888_878830115 -ORBSvcConf nul -ORBSvcConfDirective "static Server_Strategy_Factory '-ORBMaxRecvGIOPPayloadSize 268435456'"(Orb.cpp:720)
11:43:19.610 [4888.4528] <2> EndpointSelector::select_endpoint: performing call with the only endpt available!(Endpoint_Selector.cpp:431)
11:43:30.642 [4888.4528] <2> RequestInitialResources: returning
11:43:30.642 [4888.4528] <2> parse_resource_strings: MEDIADB 1 22542 PV018N 4001713 ------ 15 1306556332 1306731233 1338267233 0 144466686 7 7 8 20 0 0 1024 0 2257313 0
11:43:30.642 [4888.4528] <2> parse_resource_strings: Parsed message type 15, version 1, 21 parameters
11:43:30.642 [4888.4528] <2> parse_resource_strings: VOLUME 1 PV018N 4001713 PV018N WinGE_Clu_Sem *NULL* *NULL* 16 8 0 7 0 {00000000-0000-0000-0000-000000000000} 0
11:43:30.642 [4888.4528] <2> parse_resource_strings: Parsed message type 16, version 1, 14 parameters
11:43:30.642 [4888.4528] <2> parse_resource_strings: DRIVE 3 COMPAQ.SDLT320.001 2000101 PMC39Y0728 {3,0,0,2} -1 -1 -1 -1 -1 -1 -1 -1 *NULL* *NULL* *NULL* *NULL* 1 0 0 1 0 0
11:43:30.642 [4888.4528] <2> parse_resource_strings: Parsed message type 17, version 3, 23 parameters
11:43:30.642 [4888.4528] <2> parse_resource_strings: STORAGE 1 TNWP02662-dlt2-robot-tld-0 15 1048576 2 1 0 0 TNWP02662 TNWP02662 *NULL*
11:43:30.642 [4888.4528] <2> parse_resource_strings: Parsed message type 18, version 1, 11 parameters
11:43:30.642 [4888.4528] <2> parse_resource_strings: DISKGROUP 0 6 *NULL* 6 *NULL* 6 *NULL*
11:43:30.642 [4888.4528] <2> parse_resource_strings: Parsed message type 21, version 0, 7 parameters
11:43:30.642 [4888.4528] <2> parse_resource_strings: DISKVOLUME 0 6 *NULL* 6 *NULL* 0
11:43:30.642 [4888.4528] <2> parse_resource_strings: Parsed message type 22, version 0, 6 parameters
11:43:30.642 [4888.4528] <2> parse_resource_strings: DISKMOUNTPOINT 0 6 *NULL*
11:43:30.642 [4888.4528] <2> parse_resource_strings: Parsed message type 23, version 0, 3 parameters
11:43:30.642 [4888.4528] <2> nbjm_media_request: Job control returned to BPTM
11:43:30.642 [4888.4528] <2> drivename_open: Called with Create 1, file COMPAQ.SDLT320.001
11:43:30.642 [4888.4528] <2> drivename_checklock: Called
11:43:30.642 [4888.4528] <2> drivename_lock: lock established
11:43:30.642 [4888.4528] <4> create_tpreq_file: {3,0,0,2}
11:43:30.642 [4888.4528] <2> drivename_write: Called with mode 2
11:43:30.642 [4888.4528] <2> drivename_unlock: unlocked
11:43:30.642 [4888.4528] <2> drivename_checklock: Called
11:43:30.642 [4888.4528] <2> drivename_lock: lock established
11:43:30.642 [4888.4528] <2> openNTDevice: config_path: {3,0,0,2}, serial_num: PMC39Y0728
11:43:30.642 [4888.4528] <2> get_drive_path: SCSI coordinates {3,0,0,2}, dos_path \\.\Tape1, pnp_path \\?\scsi#sequential&ven_compaq&prod_sdlt320&rev_5f5f#5&23ce36ff&0&000002#{53f5630b-b6bf-11d0-94f2-00a0c91efb8b}
11:43:30.642 [4888.4528] <2> check_serial_num: serial number match for drive with SCSI coordinates {3,0,0,2}, dos_path \\.\Tape1, drive serial number PMC39Y0728, expected serial number PMC39Y0728
11:43:30.642 [4888.4528] <2> process_tapealert: TapeAlert returned 0x00000000 0x00000000 (from tapealert_and_release)
11:43:30.642 [4888.4528] <2> really_tpunmount: tpunmount'ing C:\Program Files\Veritas\NetBackup\db\media\tpreq\drive_COMPAQ.SDLT320.001
11:43:31.048 [4888.4528] <2> tapelib: wait_for_ltid, UnMount, timeout 2400
11:43:38.064 [4888.4528] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2048: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006
11:43:38.064 [4888.4528] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2062: service: bpdbm
11:43:38.173 [4888.4528] <2> logconnections: BPDBM CONNECT FROM 3.84.57.54.1402 TO 3.84.57.52.13724
11:43:43.049 [4888.4528] <4> report_resource_done: VBRD 1 4888 0 COMPAQ.SDLT320.001 PV018N
11:43:43.049 [4888.4528] <2> openNTDevice: config_path: {3,0,0,2}, serial_num: PMC39Y0728
11:43:43.049 [4888.4528] <2> get_drive_path: SCSI coordinates {3,0,0,2}, dos_path \\.\Tape1, pnp_path \\?\scsi#sequential&ven_compaq&prod_sdlt320&rev_5f5f#5&23ce36ff&0&000002#{53f5630b-b6bf-11d0-94f2-00a0c91efb8b}
11:43:43.049 [4888.4528] <2> check_serial_num: serial number match for drive with SCSI coordinates {3,0,0,2}, dos_path \\.\Tape1, drive serial number PMC39Y0728, expected serial number PMC39Y0728
11:43:43.064 [4888.4528] <2> process_tapealert: TapeAlert returned 0x00000000 0x00000000 (from tapealert_and_release)
11:43:43.064 [4888.4528] <2> tapealert_and_release: SCSI RELEASE
11:43:43.064 [4888.4528] <2> drivename_unlock: unlocked
11:43:43.064 [4888.4528] <2> drivename_close: Called for file COMPAQ.SDLT320.001
11:43:43.064 [4888.4528] <2> drivename_remove: Called
11:43:43.064 [4888.4528] <2> main: Sending [EXIT STATUS 0] to NBJM
11:43:43.064 [4888.4528] <2> bptm: EXITING with status 0 <----------
11:43:45.252 [2748.5572] <2> RequestSpanResources: returning
11:43:45.252 [2748.5572] <2> parse_resource_strings: MEDIADB 1 22542 PV023M 4000723 ------ 15 1306722238 1306722238 1338258238 0 0 0 0 8 20 0 0 1024 0 2 0
11:43:45.252 [2748.5572] <2> parse_resource_strings: Parsed message type 15, version 1, 21 parameters
11:43:45.252 [2748.5572] <2> parse_resource_strings: VOLUME 1 PV023M 4000723 PV023M WinGE_Clu_Sem *NULL* *NULL* 16 8 0 1 0 {00000000-0000-0000-0000-000000000000} 0
11:43:45.252 [2748.5572] <2> parse_resource_strings: Parsed message type 16, version 1, 14 parameters
11:43:45.252 [2748.5572] <2> parse_resource_strings: DRIVE 3 COMPAQ.SDLT320.001 2000101 PMC39Y0728 {3,0,0,2} -1 -1 -1 -1 -1 -1 -1 -1 *NULL* *NULL* *NULL* *NULL* 1 0 0 1 0 0
11:43:45.252 [2748.5572] <2> parse_resource_strings: Parsed message type 17, version 3, 23 parameters
11:43:45.252 [2748.5572] <2> parse_resource_strings: STORAGE 1 TNWP02662-dlt2-robot-tld-0 15 1048576 2 1 0 0 TNWP02662 TNWP02662 *NULL*
11:43:45.252 [2748.5572] <2> parse_resource_strings: Parsed message type 18, version 1, 11 parameters
11:43:45.252 [2748.5572] <2> parse_resource_strings: DISKGROUP 0 6 *NULL* 6 *NULL* 6 *NULL*
11:43:45.252 [2748.5572] <2> parse_resource_strings: Parsed message type 21, version 0, 7 parameters
11:43:45.252 [2748.5572] <2> parse_resource_strings: DISKVOLUME 0 6 *NULL* 6 *NULL* 0
11:43:45.252 [2748.5572] <2> parse_resource_strings: Parsed message type 22, version 0, 6 parameters
11:43:45.252 [2748.5572] <2> parse_resource_strings: DISKMOUNTPOINT 0 6 *NULL*
11:43:45.252 [2748.5572] <2> parse_resource_strings: Parsed message type 23, version 0, 3 parameters
11:43:45.252 [2748.5572] <2> nbjm_media_request: Job control returned to BPTM
11:43:45.252 [2748.5572] <2> drivename_open: Called with Create 1, file COMPAQ.SDLT320.001
11:43:45.252 [2748.5572] <2> drivename_checklock: Called
11:43:45.252 [2748.5572] <2> drivename_lock: lock established
11:43:45.252 [2748.5572] <2> drivename_write: Called with mode 0
11:43:45.252 [2748.5572] <2> drivename_unlock: unlocked
11:43:45.252 [2748.5572] <2> drivename_checklock: Called
11:43:45.252 [2748.5572] <2> drivename_lock: lock established
11:43:45.252 [2748.5572] <2> db_byid: search for media id PV023M
11:43:45.252 [2748.5572] <2> send_MDS_msg: MEDIADB 1 22542 PV023M 4000723 *NULL* 15 1306773682 0 0 0 0 0 0 0 20 0 16 1024 0 0 0
11:43:45.252 [2748.5572] <2> bptm media_id_to_monitor: job_id = 97034
11:43:45.252 [2748.5572] <2> bptm media_id_to_monitor: pDstMediaId = PV023M
11:43:45.252 [2748.5572] <2> mount_open_media: Waiting for mount of media id PV023M (copy 1) on server TNWP02662.
11:43:45.252 [2748.5572] <2> openNTDevice: config_path: {3,0,0,2}, serial_num: PMC39Y0728
11:43:45.252 [2748.5572] <2> get_drive_path: SCSI coordinates {3,0,0,2}, dos_path \\.\Tape1, pnp_path \\?\scsi#sequential&ven_compaq&prod_sdlt320&rev_5f5f#5&23ce36ff&0&000002#{53f5630b-b6bf-11d0-94f2-00a0c91efb8b}
11:43:45.252 [2748.5572] <2> check_serial_num: serial number match for drive with SCSI coordinates {3,0,0,2}, dos_path \\.\Tape1, drive serial number PMC39Y0728, expected serial number PMC39Y0728
11:43:45.252 [2748.5572] <2> manage_scsi_reserve: SCSI RESERVE
11:43:45.877 [2748.5572] <2> tapelib: wait_for_ltid, Mount, timeout 0
11:44:49.880 [2748.5572] <2> openTpreqFile: tpreq_file: C:\Program Files\Veritas\NetBackup\db\media\tpreq\drive_COMPAQ.SDLT320.001, serial_num: PMC39Y0728
11:44:49.880 [2748.5572] <2> get_drive_path: SCSI coordinates {3,0,0,2}, dos_path \\.\Tape1, pnp_path \\?\scsi#sequential&ven_compaq&prod_sdlt320&rev_5f5f#5&23ce36ff&0&000002#{53f5630b-b6bf-11d0-94f2-00a0c91efb8b}
11:44:49.880 [2748.5572] <2> check_serial_num: serial number match for drive with SCSI coordinates {3,0,0,2}, dos_path \\.\Tape1, drive serial number PMC39Y0728, expected serial number PMC39Y0728
11:44:49.880 [2748.5572] <2> init_tape: \\.\Tape1 (SCSI coordinates {3,0,0,2}) configured with blocksize 0
11:44:49.880 [2748.5572] <2> init_tape: \\.\Tape1 (SCSI coordinates {3,0,0,2}) has compression enabled
11:44:49.880 [2748.5572] <2> io_open: SCSI RESERVE
11:44:49.880 [2748.5572] <2> manage_drive_attributes: report_attr, fl1 0x00001005, fl2 0x00000000
11:44:55.193 [2748.5572] <2> io_open: file C:\Program Files\Veritas\NetBackup\db\media\tpreq\drive_COMPAQ.SDLT320.001 successfully opened (mode 2)
11:44:55.193 [2748.5572] <2> write_backup: media id PV023M mounted on drive index 1, drivepath {3,0,0,2}, drivename COMPAQ.SDLT320.001, copy 1
11:44:55.209 [2748.5572] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2048: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006
11:44:55.209 [2748.5572] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2062: service: bpdbm
11:44:55.365 [2748.5572] <2> logconnections: BPDBM CONNECT FROM 3.84.57.54.1511 TO 3.84.57.52.13724
11:45:00.240 [2748.5572] <4> report_throughput: VBRT 1 2748 1 1 COMPAQ.SDLT320.001 PV023M 0 1 0 0  0 (bptm.c.20291)
11:45:00.240 [2748.5572] <2> io_read_media_header: drive index 1, reading media header, buflen = 65536, buff = 0x116b6c0, copy 1
11:45:00.240 [2748.5572] <2> io_ioctl: command (5)MTREW 1 from (bptm.c.8159) on drive index 1
11:45:08.241 [2748.5572] <2> io_write_media_header: drive index 1, writing media header
11:45:08.241 [2748.5572] <2> io_ioctl: command (5)MTREW 1 from (bptm.c.9470) on drive index 1
11:45:08.381 [2748.5572] <2> io_ioctl: command (0)MTWEOF 1 from (bptm.c.9511) on drive index 1
11:45:16.600 [2748.5572] <2> io_write_media_header: report_density, 0x49 [QUANTUM SDLT320]
11:45:16.600 [2748.5572] <2> send_MDS_msg: VOL_UPDATE 0 22542 PV023M 4000723 *NULL* *NULL* 4 73
11:45:16.600 [2748.5572] <2> send_MDS_msg: MEDIADB 1 22542 PV023M 4000723 *NULL* 15 1306773682 1306773682 1307378482 0 0 0 0 0 20 0 0 1024 0 0 0
11:45:16.632 [2748.5572] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2048: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006
11:45:16.632 [2748.5572] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2062: service: bpdbm
11:45:16.803 [2748.5572] <2> logconnections: BPDBM CONNECT FROM 3.84.57.54.1540 TO 3.84.57.52.13724
11:45:21.679 [2748.5572] <4> write_backup: begin writing backup id TNWP02662_1306773682, copy 1, fragment 1, to media id PV023M on drive COMPAQ.SDLT320.001 (index 1)
11:45:21.679 [2748.5572] <2> signal_parent: set bpbrm media ready event (pid = 3088)
11:45:21.679 [2748.5572] <2> write_data: twin_index: 0 active: 1 dont_process: 0 wrote_backup_hdr: 0 finished_buff: 0 saved_cindex: 0 twin_is_disk 0 delay_brm: 0
11:45:21.679 [2748.5572] <2> write_data: Total Kbytes transferred 0
11:45:21.679 [2748.5572] <2> write_data: absolute block position prior to writing backup header(s) is 2, copy 1
11:45:21.679 [2748.5572] <2> io_write_back_header: drive index 1, TNWP02662_1306773682, file num = 1, mpx_headers = 0, copy 1
11:45:21.679 [2748.5572] <2> write_data: completed writing backup header, start writing data when first buffer is available, copy 1
11:51:46.698 [7292.7804] <2> bptm: INITIATING (VERBOSE = 0): -rptdrv -jobid -1306721458 -jm
11:51:46.698 [7292.7804] <2> drivename_open: Called with Create 0, file COMPAQ.SDLT320.001
11:51:46.698 [7292.7804] <2> drivename_checklock: Called
11:51:46.698 [7292.7804] <2> drivename_checklock: File is locked
11:51:46.698 [7292.7804] <2> report_drives: DRIVE = COMPAQ.SDLT320.001 LOCK = TRUE CURTIME = 1306774306
11:51:46.698 [7292.7804] <2> drivename_close: Called for file COMPAQ.SDLT320.001
11:51:46.698 [7292.7804] <2> report_drives: MODE = 0
11:51:46.698 [7292.7804] <2> report_drives: TIME = 1306773825
11:51:46.698 [7292.7804] <2> report_drives: MASTER = TNWP02981
11:51:46.698 [7292.7804] <2> report_drives: SR_KEY = 0 1
11:51:46.698 [7292.7804] <2> report_drives: PATH = {3,0,0,2}
11:51:46.698 [7292.7804] <2> report_drives: MEDIA = PV023M
11:51:46.698 [7292.7804] <2> report_drives: REQID = -1306721447
11:51:46.698 [7292.7804] <2> report_drives: ALOCID = 22542
11:51:46.698 [7292.7804] <2> report_drives: RBID = {C55CC8B7-9C80-4C4E-A1E9-FDE2F7EFEB11}
11:51:46.698 [7292.7804] <2> report_drives: PID = 2748
11:51:46.698 [7292.7804] <2> report_drives: FILE = C:\Program Files\Veritas\NetBackup\db\media\tpreq\drive_COMPAQ.SDLT320.001
11:51:46.698 [7292.7804] <2> main: Sending [EXIT STATUS 0] to NBJM
11:51:46.698 [7292.7804] <2> bptm: EXITING with status 0 <----------
12:01:47.286 [7064.8112] <2> bptm: INITIATING (VERBOSE = 0): -rptdrv -jobid -1306721464 -jm
12:01:47.286 [7064.8112] <2> drivename_open: Called with Create 0, file COMPAQ.SDLT320.001
12:01:47.286 [7064.8112] <2> drivename_checklock: Called
12:01:47.286 [7064.8112] <2> drivename_checklock: File is locked
12:01:47.286 [7064.8112] <2> report_drives: DRIVE = COMPAQ.SDLT320.001 LOCK = TRUE CURTIME = 1306774907
12:01:47.286 [7064.8112] <2> drivename_close: Called for file COMPAQ.SDLT320.001
12:01:47.286 [7064.8112] <2> report_drives: MODE = 0
12:01:47.286 [7064.8112] <2> report_drives: TIME = 1306773825
12:01:47.286 [7064.8112] <2> report_drives: MASTER = TNWP02981
12:01:47.286 [7064.8112] <2> report_drives: SR_KEY = 0 1
12:01:47.286 [7064.8112] <2> report_drives: PATH = {3,0,0,2}
12:01:47.286 [7064.8112] <2> report_drives: MEDIA = PV023M
12:01:47.286 [7064.8112] <2> report_drives: REQID = -1306721447
12:01:47.286 [7064.8112] <2> report_drives: ALOCID = 22542
12:01:47.286 [7064.8112] <2> report_drives: RBID = {C55CC8B7-9C80-4C4E-A1E9-FDE2F7EFEB11}
12:01:47.286 [7064.8112] <2> report_drives: PID = 2748
12:01:47.286 [7064.8112] <2> report_drives: FILE = C:\Program Files\Veritas\NetBackup\db\media\tpreq\drive_COMPAQ.SDLT320.001
12:01:47.286 [7064.8112] <2> main: Sending [EXIT STATUS 0] to NBJM
12:01:47.286 [7064.8112] <2> bptm: EXITING with status 0 <----------
12:08:20.071 [2748.5572] <2> mm_check_child_write: Received Terminate Event
12:08:20.071 [2748.5572] <2> io_terminate_tape: writing empty backup header, drive index 1, copy 1
12:08:20.071 [2748.5572] <2> io_terminate_tape: reposition to previous tapemark and rewrite header
12:08:20.071 [2748.5572] <2> io_ioctl: command (2)MTBSF 1 from (bptm.c.8678) on drive index 1
12:08:20.087 [2748.5572] <2> io_ioctl: command (0)MTWEOF 1 from (bptm.c.8709) on drive index 1
12:08:21.649 [2748.5572] <2> io_terminate_tape: absolute block position prior to writing empty header is 2, copy 1
12:08:21.649 [2748.5572] <2> io_write_back_header: drive index 1, empty_file, file num = 1, mpx_headers = 0, copy 1

 

Can you help me...

Regards

 

0 Kudos
0 REPLIES 0