cancel
Showing results for 
Search instead for 
Did you mean: 

The odd backup speed in Veritas NBU6.0

kylix
Level 4

Enterprise Working Environment:

Veritas NBU Version: VERITAS NBU6.0

Veritas NBU Master Server:  SUN Solaris9 Sparc64   IP Address: 10.87.0.xxx

Veritas NBU Client Server:  Microsoft windows 2003   IP Address: 10.67.0.yyy

Circumstance Description:

Recently, when we backup a windows 2003 server(10.67.0.yyy),  we found an odd phenomenon which the difference of backup speed was very large. Sometimes the backup speed was about 10MB/S, sometimes it was only 5-15KB/s. the backup speed was not very stable.

Primary Analysis Action:

When we download and upload data between the server and NBU Master server by FTP tool, the speed was stable, about 10MB/s.

we also had some other testing to find the root cause. But we cound not get the real reason.  

At first, I doubt the network limit to cause the issue. But FTP can work properly. Now I did not know what was the root cause.

 

Anyone can help me ?

Thanks a lot.

kylix

 

11 REPLIES 11

Eric_Zhang
Level 5

basicly, the backup speed is related to the file size , when Netbackup backing up very small files , the speed will be very solw , but when Netbackup backing up large files , it will speed up .

please check and confirm . Thanks

kylix
Level 4

Thanks for your reply.  I know what you said. But the backup size and quantity are almost same every time. 

Marianne
Level 6
Partner    VIP    Accredited Certified

Lots of other processes running on this client can influence backup speed, for example, a full virus scan. The place to start is the client itself - check what's running when you see slow backup speed.

Ensure that you have bpbkar log on this client for further troubleshooting.

watsons
Level 6

Check if the policy had these few options turn on. They can slow down your backup.

If not, you might need to collect bpbrm, bptm logs on meda server, bpbkar log on client, then analyze through this technote: http://www.symantec.com/docs/TECH18422

kylix
Level 4

Today, at last I get log informations of low backup speed,

bpbkar log on client:

11:37:19.005 AM: [7084.256] <4> ov_log::OVInit: INF - Starting log file: C:\VERITAS1\NetBackup\logs\BPBKAR\050311.LOG

11:37:19.005 AM: [7084.256] <4> ov_log::OVInit: GENERAL Log Level: 0
11:37:19.005 AM: [7084.256] <4> ov_log::OVInit: TCP Log Level: 0
11:37:19.005 AM: [7084.256] <4> ov_log::OVInit: INF - the log mutex: 1964
BPBKAR  NetBackup Backup/Archive  6.0GA  [Nov  7 2006]
Copyright 1993 - 2005 VERITAS Software Corporation
All Rights Reserved.

11:37:19.005 AM: [7084.256] <2> WinMain: DAT - _pgmptr = 'C:\VERITAS1\NetBackup\bin\bpbkar32.exe'
11:37:19.005 AM: [7084.256] <2> WinMain: DAT - lpCmdLine = '-r 1209600 -ru root -dt 0 -to 0 -clnt 10.67.99.227 -class File_10.67.99.227 -sched Full -st FULL -bpstart_to 300 -bpend_to 300 -read_to 3000 -use_otm -fso -b 10.67.99.227_1304393831 -WOFB_enabled -WOFB_fim 0 -WOFB_usage 0 -WOFB_error 0 -ct 13 -use_ofb '
11:37:19.005 AM: [7084.256] <2> date_debug: DAT - timezone: China Standard Time, offset=-28800, dst: NONE
11:37:19.005 AM: [7084.256] <2> date_debug: DAT - current time: 1304393839, 5/3/2011 11:37:19 AM
11:37:19.005 AM: [7084.256] <2> date_debug: DAT - 01/01/94 UCT:  757382400, 1/1/1994 8:00:00 AM
11:37:19.005 AM: [7084.256] <2> date_debug: DAT - 07/01/94 UCT:  773020800, 7/1/1994 8:00:00 AM
11:37:19.005 AM: [7084.256] <2> WinMain: DAT - standard input handle = 376
11:37:19.005 AM: [7084.256] <2> WinMain: DAT - standard output handle = 1960
11:37:19.005 AM: [7084.256] <2> WinMain: DAT - standard error handle = 1904
11:37:19.020 AM: [7084.256] <4> tar_backup::V_SetupJobData: INF - dwJobData: ffffffff
11:37:19.020 AM: [7084.256] <4> tar_backup::V_SetupJobData: INF -     dwJob: ffffffff
11:37:19.036 AM: [7084.256] <4> dos_backup::V_PreProcessing: INF - backup privileges enabled, previous = 0
11:37:19.036 AM: [7084.256] <4> dos_backup::V_PreProcessing: INF - restore privileges enabled, previous = 0
11:37:19.036 AM: [7084.256] <4> dos_backup::V_PreProcessing: INF - security privileges enabled, previous = 0
11:37:19.036 AM: [7084.256] <4> dos_backup::V_PreProcessing: INF - tcb privileges enabled, previous = 0
11:37:19.036 AM: [7084.256] <4> dos_backup::V_PreProcessing: INF - create token privileges enabled, previous = 0
11:37:19.036 AM: [7084.256] <4> dos_backup::V_PreProcessing: INF - user name: root
11:37:19.036 AM: [7084.256] <4> dos_backup::V_PreProcessing: INF - no access token: scheduled backup
11:37:19.286 AM: [7084.256] <2> tar_backup::V_SetupProcessContinue: TAR - CONTINUE BACKUP received
11:37:19.286 AM: [7084.256] <2> tar_backup::V_SetupFileDirectives: TAR - backup filename = D:\baksql
11:37:19.286 AM: [7084.256] <2> tar_backup::V_SetupFileDirectives: TAR - backup filename = D:\baksql
11:37:19.286 AM: [7084.4972] <4> tar_base::V_KeepaliveThread: INF - The Keepalive thread is active. Keepalive interval 60 Seconds
11:37:19.317 AM: [7084.256] <2> ov_log::V_GlobalLog: INF - BEDS_Init() Enter InitFlags:0x0
11:38:05.100 AM: [7084.256] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: C:
11:38:05.100 AM: [7084.256] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: D:
11:38:05.100 AM: [7084.256] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: E:
11:38:05.100 AM: [7084.256] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Microsoft Terminal Services
11:38:05.100 AM: [7084.256] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Microsoft Windows Network
11:38:05.100 AM: [7084.256] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Web Client Network
11:38:05.100 AM: [7084.256] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Oracle
11:38:05.100 AM: [7084.256] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Shadow?Copy?Components
11:38:07.194 AM: [7084.256] <4> backup_create: INF - NetBackup Temp Directory: 'C:\VERITAS1\\NetBackup\Temp'
11:38:07.194 AM: [7084.256] <2> tar_base::V_vTarMsgW: INF - Enabling volume snapshots for (D:\baksql), please wait...
11:38:07.194 AM: [7084.256] <4> V_DetermineMountInfo: INF - Checking Volume \\?\Volume{0cf4f728-4fd5-11dc-9700-001b789662ec}\ for '\baksql'
11:38:07.194 AM: [7084.256] <4> V_DetermineMountInfo: INF -   Adding NTFS Volume D:\ ==> \\?\Volume{0cf4f728-4fd5-11dc-9700-001b789662ec}\
11:38:07.194 AM: [7084.256] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - ======================================================================
11:38:07.194 AM: [7084.256] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - Attempting to create snapshots for 'D:\baksql'
11:38:07.194 AM: [7084.256] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - CREATE request: C:\VERITAS1\NetBackup\bin\bpfis create -owner NBU -fso -WOFB -fim VSP -id 10.67.99.227_1304393831 "D:\baksql"
11:38:14.210 AM: [7084.256] <4> V_Snapshot::V_Snapshot_ParseBpfisOutput: INF - Snapshot creation, FIS_ID: 10.67.99.227_1304393831
11:38:14.210 AM: [7084.256] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - Snapshot creation was not successful
11:38:14.210 AM: [7084.256] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - ======================================================================
11:38:14.210 AM: [7084.256] <2> tar_base::V_vTarMsgW: INF - Volume snapshots not enabled
12:38:07.895 PM: [7084.256] <4> ov_log::OVLoop: Timestamp
 

 

bptm log on media server:

11:37:19.532 [27396] <2> bptm: INITIATING (VERBOSE = 0): -w -c 10.67.99.227 -den 14 -rt 8 -rn 0 -stunit bckserver01-hcart2-robot-tld-0 -cl File_10.67.99.227 -bt 1304393831 -b 10.67.99.227_1304393831 -st 0 -cj 2 -p NetBackup -reqid -1303437597 -jm -brm -hostname 10.67.99.227 -ru root -rclnt 10.67.99.227 -rclnthostname 10.67.99.227 -rl 1 -rp 1209600 -sl Full -ct 13 -maxfrag 1048576 -mediasvr bckserver01 -no_callback -connect_options 0x01010100 -jobid 147385 -jobgrpid 147385 -masterversion 600000
11:37:19.541 [27396] <2> bptm: EMMserver_name = bckserver01
11:37:19.541 [27396] <2> bptm: EMMserver_port = 1556
11:37:19.550 [27396] <4> db_getSTUNIT: emmserver_name = bckserver01
11:37:19.550 [27396] <4> db_getSTUNIT: emmserver_port = 1556
11:37:19.617 [27396] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:37:19.617 [27396] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:37:19.626 [27396] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name bckserver01
11:37:19.696 [27396] <2> io_init: using 6553600 data buffer size
11:37:19.696 [27396] <2> io_init: CINDEX 0, sched Kbytes for monitoring = 40000
11:37:19.696 [27396] <2> io_set_recvbuf: setting receive network buffer to 65536 bytes
11:37:19.696 [27396] <2> io_init: using 16 data buffers
11:37:19.696 [27396] <2> io_init: child delay = 20, parent delay = 30 (milliseconds)
11:37:19.697 [27396] <2> create_shared_memory: shm_size = 104857988, buffer address = 0xf6400000, buf control = 0xfc800000, ready ptr = 0xfc800180
11:37:19.709 [27396] <2> LOCAL CLASS_ATT_DEFS: Product ID = 6
11:37:19.720 [27396] <2> get_behavior_rec: invalid entry: POSTPROCESS_IMAGES
11:37:19.748 [27396] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2034: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006
11:37:19.748 [27396] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2048: service: bpdbm
11:37:19.793 [27396] <2> logconnections: BPDBM CONNECT FROM 10.87.0.198.58908 TO 10.87.0.198.13724
11:37:19.795 [27396] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:37:19.795 [27396] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:37:19.796 [27396] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:37:19.796 [27396] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:37:19.992 [27396] <2> nbjm_media_request: Passing job control to NBJM, type WRITE
11:37:19.994 [27396] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:37:19.994 [27396] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:37:19.995 [27396] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name bckserver01
11:37:22.222 [27396] <2> RequestMultipleResources: returning
11:37:22.222 [27396] <2> parse_resource_strings: MEDIADB 1 89554 0019L3 4000038 ------ 14 1304387108 1304387230 1305596830 0 21606669 2 2 1 1 0 0 1024 0 3384 0
11:37:22.222 [27396] <2> parse_resource_strings: Parsed message type 15, version 1, 21 parameters
11:37:22.222 [27396] <2> parse_resource_strings: VOLUME 1 0019L3 4000038 000019L3 NetBackup IMATION 0C31582260 14 8 0 2 0 {00000000-0000-0000-0000-000000000000} 0
11:37:22.222 [27396] <2> parse_resource_strings: Parsed message type 16, version 1, 14 parameters
11:37:22.222 [27396] <2> parse_resource_strings: DRIVE 2 IBM.ULTRIUM-TD3.000 2000006 F09903E000 /dev/rmt/0cbn -1 -1 -1 -1 0 0 0 0 *NULL* *NULL* *NULL* *NULL* 1 0
11:37:22.222 [27396] <2> parse_resource_strings: Parsed message type 17, version 2, 19 parameters
11:37:22.222 [27396] <2> parse_resource_strings: STORAGE 0 bckserver01-hcart2-robot-tld-0 14 1048576
11:37:22.222 [27396] <2> parse_resource_strings: Parsed message type 18, version 0, 4 parameters
11:37:22.222 [27396] <2> nbjm_media_request: Job control returned to BPTM
11:37:22.222 [27396] <2> drivename_open: Called with Create 1, file IBM.ULTRIUM-TD3.000
11:37:22.223 [27396] <2> drivename_lock: lock established
11:37:22.223 [27396] <2> drivename_write: Called with mode 0
11:37:22.226 [27396] <2> write_backup: backup child process is pid 27399
11:37:22.226 [27396] <2> mount_open_media: Waiting for mount of media id 0019L3 (copy 1) on server bckserver01.
11:37:22.228 [27396] <4> create_tpreq_file: symlink to path /dev/rmt/0cbn
11:37:22.234 [27396] <2> manage_scsi_reserve: SCSI RESERVE
11:37:22.237 [27396] <4> expandpath: /usr/openv/netbackup/db/media/tpreq/drive_IBM.ULTRIUM-TD3.000
11:38:02.416 [27396] <2> io_open: SCSI RESERVE
11:38:02.439 [27396] <2> io_open: report_attr, fl1 0x00000049, fl2 0x00000004
11:38:02.439 [27396] <2> io_open: Matching medium manufacturer [IMATION], sn [0C31582260]
11:38:02.466 [27396] <2> io_open: file /usr/openv/netbackup/db/media/tpreq/drive_IBM.ULTRIUM-TD3.000 successfully opened (mode 2)
11:38:02.466 [27396] <2> write_backup: media id 0019L3 mounted on drive index 2, drivepath /dev/rmt/0cbn, drivename IBM.ULTRIUM-TD3.000, copy 1
11:38:02.466 [27396] <2> io_read_media_header: drive index 2, reading media header, buflen = 65536, buff = 0x399318, copy 1
11:38:02.467 [27396] <2> io_ioctl: command (5)MTREW 1 from (bptm.c.7675) on drive index 2
11:38:02.496 [27396] <2> io_ioctl: command (1)MTFSF 1 from (bptm.c.7928) on drive index 2
11:38:02.505 [27396] <2> io_position_for_write: position media id 0019L3, copy 1, current number images = 2
11:38:02.505 [27396] <2> io_position_for_write: locating to absolute block number 3384, copy 1
11:38:58.911 [27396] <2> io_position_for_write: locate block is done
11:39:00.434 [27396] <2> io_position_for_write: processing empty header, filenum = 3, bid = (empty_file), copy 1
11:39:00.434 [27396] <2> io_position_for_write: empty header found on 0019L3, OK, copy 1
11:39:00.434 [27396] <2> io_ioctl: command (2)MTBSF 1 from (bptm.c.20241) on drive index 2
11:39:00.446 [27396] <2> io_ioctl: command (0)MTWEOF 1 from (bptm.c.20275) on drive index 2
11:39:05.416 [27396] <2> send_MDS_msg: MEDIADB 1 89554 0019L3 4000038 *NULL* 14 1304387108 1304393831 1305603431 0 21606669 2 2 1 1 0 0 1024 0 3384 0
11:39:05.419 [27396] <2> JobInst::sendIrmMsg: returning
11:39:05.420 [27396] <2> db_error_add_to_file: dberrorq.c:midnite = 1304352000
11:39:05.432 [27396] <4> write_backup: begin writing backup id 10.67.99.227_1304393831, copy 1, fragment 1, to media id 0019L3 on drive index 2
11:39:05.432 [27396] <2> signal_parent: sending SIGUSR1 to bpbrm (pid = 27395)
11:39:05.433 [27396] <2> write_data: ndmp_dup_max_frag is set to 1073741824000
11:39:05.433 [27396] <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:39:05.438 [27396] <2> write_data: absolute block position prior to writing backup header(s) is 3384, copy 1
11:39:05.438 [27396] <2> io_write_back_header: drive index 2, 10.67.99.227_1304393831, file num = 3, mpx_headers = 0, copy 1
11:39:05.445 [27396] <2> write_data: completed writing backup header, start writing data when first buffer is available, copy 1
11:39:39.255 [27456] <2> bptm: INITIATING (VERBOSE = 0): -delete_expired
11:39:39.264 [27456] <2> bptm: EXITING with status 0 <----------
11:39:39.750 [27460] <2> bptm: INITIATING (VERBOSE = 0): -delete_all_expired
11:39:39.760 [27460] <2> bptm: EMMserver_name = bckserver01
11:39:39.760 [27460] <2> bptm: EMMserver_port = 1556
11:39:39.821 [27460] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:39:39.821 [27460] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:39:39.831 [27460] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name bckserver01
11:39:39.938 [27460] <2> bptm: EXITING with status 0 <----------
11:46:10.118 [27629] <2> bptm: INITIATING (VERBOSE = 0): -rptdrv -jobid -1303437598 -jm
11:46:10.127 [27629] <2> bptm: EMMserver_name = bckserver01
11:46:10.127 [27629] <2> bptm: EMMserver_port = 1556
11:46:10.128 [27629] <2> drivename_open: Called with Create 0, file IBM.ULTRIUM-TD3.000
11:46:10.128 [27629] <2> drivename_checklock: Called
11:46:10.128 [27629] <2> drivename_checklock: PID 27396 has lock
11:46:10.128 [27629] <2> report_drives: MODE = 0
11:46:10.128 [27629] <2> report_drives: TIME = 1304393842
11:46:10.128 [27629] <2> report_drives: MASTER = bckserver01
11:46:10.128 [27629] <2> report_drives: PATH = /dev/rmt/0cbn
11:46:10.128 [27629] <2> report_drives: MEDIA = 0019L3
11:46:10.128 [27629] <2> report_drives: REQID = -1303437597
11:46:10.128 [27629] <2> report_drives: ALOCID = 89554
11:46:10.128 [27629] <2> report_drives: RBID = {A1298978-1DD1-11B2-8BF1-00144F8FEBCA}
11:46:10.128 [27629] <2> report_drives: PID = 27396
11:46:10.128 [27629] <2> report_drives: FILE = /usr/openv/netbackup/db/media/tpreq/drive_IBM.ULTRIUM-TD3.000
11:46:10.129 [27629] <2> main: Sending [EXIT STATUS 0] to NBJM
11:46:10.129 [27629] <2> bptm: EXITING with status 0 <----------
11:49:40.665 [27710] <2> bptm: INITIATING (VERBOSE = 0): -delete_expired
11:49:40.674 [27710] <2> bptm: EXITING with status 0 <----------
11:49:41.180 [27714] <2> bptm: INITIATING (VERBOSE = 0): -delete_all_expired
11:49:41.190 [27714] <2> bptm: EMMserver_name = bckserver01
11:49:41.190 [27714] <2> bptm: EMMserver_port = 1556
11:49:41.259 [27714] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:49:41.259 [27714] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:49:41.269 [27714] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name bckserver01
11:49:41.374 [27714] <2> bptm: EXITING with status 0 <----------
11:53:16.080 [27396] <2> write_data: first write, twin_index: 0 cindex: 0 dont_process: 1 wrote_backup_hdr: 1 finished_buff: 0
11:53:16.080 [27396] <2> write_data: received first buffer (6553600 bytes), begin writing data
11:56:10.608 [27886] <2> bptm: INITIATING (VERBOSE = 0): -rptdrv -jobid -1303437601 -jm
11:56:10.617 [27886] <2> bptm: EMMserver_name = bckserver01
11:56:10.617 [27886] <2> bptm: EMMserver_port = 1556
11:56:10.617 [27886] <2> drivename_open: Called with Create 0, file IBM.ULTRIUM-TD3.000
11:56:10.618 [27886] <2> drivename_checklock: Called
11:56:10.618 [27886] <2> drivename_checklock: PID 27396 has lock
11:56:10.618 [27886] <2> report_drives: MODE = 0
11:56:10.618 [27886] <2> report_drives: TIME = 1304393842
11:56:10.618 [27886] <2> report_drives: MASTER = bckserver01
11:56:10.618 [27886] <2> report_drives: PATH = /dev/rmt/0cbn
11:56:10.618 [27886] <2> report_drives: MEDIA = 0019L3
11:56:10.618 [27886] <2> report_drives: REQID = -1303437597
11:56:10.618 [27886] <2> report_drives: ALOCID = 89554
11:56:10.618 [27886] <2> report_drives: RBID = {A1298978-1DD1-11B2-8BF1-00144F8FEBCA}
11:56:10.618 [27886] <2> report_drives: PID = 27396
11:56:10.618 [27886] <2> report_drives: FILE = /usr/openv/netbackup/db/media/tpreq/drive_IBM.ULTRIUM-TD3.000
11:56:10.618 [27886] <2> main: Sending [EXIT STATUS 0] to NBJM
11:56:10.618 [27886] <2> bptm: EXITING with status 0 <----------
11:59:42.088 [27971] <2> bptm: INITIATING (VERBOSE = 0): -delete_expired
11:59:42.098 [27971] <2> bptm: EXITING with status 0 <----------
11:59:42.599 [27975] <2> bptm: INITIATING (VERBOSE = 0): -delete_all_expired
11:59:42.608 [27975] <2> bptm: EMMserver_name = bckserver01
11:59:42.608 [27975] <2> bptm: EMMserver_port = 1556
11:59:42.672 [27975] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:59:42.672 [27975] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:59:42.681 [27975] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name bckserver01
11:59:42.787 [27975] <2> bptm: EXITING with status 0 <----------
12:06:10.217 [28145] <2> bptm: INITIATING (VERBOSE = 0): -rptdrv -jobid -1303437604 -jm
12:06:10.229 [28145] <2> bptm: EMMserver_name = bckserver01
12:06:10.229 [28145] <2> bptm: EMMserver_port = 1556
12:06:10.230 [28145] <2> drivename_open: Called with Create 0, file IBM.ULTRIUM-TD3.000
12:06:10.230 [28145] <2> drivename_checklock: Called
12:06:10.230 [28145] <2> drivename_checklock: PID 27396 has lock
12:06:10.230 [28145] <2> report_drives: MODE = 0
12:06:10.231 [28145] <2> report_drives: TIME = 1304393842
12:06:10.231 [28145] <2> report_drives: MASTER = bckserver01
12:06:10.231 [28145] <2> report_drives: PATH = /dev/rmt/0cbn
12:06:10.231 [28145] <2> report_drives: MEDIA = 0019L3
12:06:10.231 [28145] <2> report_drives: REQID = -1303437597
12:06:10.231 [28145] <2> report_drives: ALOCID = 89554
12:06:10.231 [28145] <2> report_drives: RBID = {A1298978-1DD1-11B2-8BF1-00144F8FEBCA}
12:06:10.231 [28145] <2> report_drives: PID = 27396
12:06:10.231 [28145] <2> report_drives: FILE = /usr/openv/netbackup/db/media/tpreq/drive_IBM.ULTRIUM-TD3.000
12:06:10.233 [28145] <2> main: Sending [EXIT STATUS 0] to NBJM
12:06:10.233 [28145] <2> bptm: EXITING with status 0 <----------
12:09:43.541 [28233] <2> bptm: INITIATING (VERBOSE = 0): -delete_expired
12:09:43.550 [28233] <2> bptm: EXITING with status 0 <----------
12:09:44.039 [28237] <2> bptm: INITIATING (VERBOSE = 0): -delete_all_expired
12:09:44.049 [28237] <2> bptm: EMMserver_name = bckserver01
12:09:44.049 [28237] <2> bptm: EMMserver_port = 1556
12:09:44.114 [28237] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
12:09:44.115 [28237] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
12:09:44.125 [28237] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name bckserver01
12:09:44.232 [28237] <2> bptm: EXITING with status 0 <----------
12:16:10.213 [28402] <2> bptm: INITIATING (VERBOSE = 0): -rptdrv -jobid -1303437607 -jm
12:16:10.222 [28402] <2> bptm: EMMserver_name = bckserver01
12:16:10.222 [28402] <2> bptm: EMMserver_port = 1556
12:16:10.223 [28402] <2> drivename_open: Called with Create 0, file IBM.ULTRIUM-TD3.000
12:16:10.223 [28402] <2> drivename_checklock: Called
12:16:10.223 [28402] <2> drivename_checklock: PID 27396 has lock
12:16:10.223 [28402] <2> report_drives: MODE = 0
12:16:10.223 [28402] <2> report_drives: TIME = 1304393842
12:16:10.223 [28402] <2> report_drives: MASTER = bckserver01
12:16:10.223 [28402] <2> report_drives: PATH = /dev/rmt/0cbn
12:16:10.223 [28402] <2> report_drives: MEDIA = 0019L3
12:16:10.223 [28402] <2> report_drives: REQID = -1303437597
12:16:10.223 [28402] <2> report_drives: ALOCID = 89554
12:16:10.223 [28402] <2> report_drives: RBID = {A1298978-1DD1-11B2-8BF1-00144F8FEBCA}
12:16:10.223 [28402] <2> report_drives: PID = 27396
12:16:10.223 [28402] <2> report_drives: FILE = /usr/openv/netbackup/db/media/tpreq/drive_IBM.ULTRIUM-TD3.000
12:16:10.224 [28402] <2> main: Sending [EXIT STATUS 0] to NBJM
12:16:10.224 [28402] <2> bptm: EXITING with status 0 <----------
12:19:44.961 [28482] <2> bptm: INITIATING (VERBOSE = 0): -delete_expired
12:19:44.971 [28482] <2> bptm: EXITING with status 0 <----------
12:19:45.471 [28486] <2> bptm: INITIATING (VERBOSE = 0): -delete_all_expired
12:19:45.481 [28486] <2> bptm: EMMserver_name = bckserver01
12:19:45.481 [28486] <2> bptm: EMMserver_port = 1556
12:19:45.546 [28486] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
12:19:45.546 [28486] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
12:19:45.555 [28486] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name bckserver01
12:19:45.658 [28486] <2> bptm: EXITING with status 0 <----------
12:26:10.245 [28658] <2> bptm: INITIATING (VERBOSE = 0): -rptdrv -jobid -1303437610 -jm
12:26:10.254 [28658] <2> bptm: EMMserver_name = bckserver01
12:26:10.254 [28658] <2> bptm: EMMserver_port = 1556
12:26:10.254 [28658] <2> drivename_open: Called with Create 0, file IBM.ULTRIUM-TD3.000
12:26:10.254 [28658] <2> drivename_checklock: Called
12:26:10.254 [28658] <2> drivename_checklock: PID 27396 has lock
12:26:10.254 [28658] <2> report_drives: MODE = 0
12:26:10.254 [28658] <2> report_drives: TIME = 1304393842
12:26:10.254 [28658] <2> report_drives: MASTER = bckserver01
12:26:10.254 [28658] <2> report_drives: PATH = /dev/rmt/0cbn
12:26:10.254 [28658] <2> report_drives: MEDIA = 0019L3
12:26:10.254 [28658] <2> report_drives: REQID = -1303437597
12:26:10.254 [28658] <2> report_drives: ALOCID = 89554
12:26:10.254 [28658] <2> report_drives: RBID = {A1298978-1DD1-11B2-8BF1-00144F8FEBCA}
12:26:10.254 [28658] <2> report_drives: PID = 27396
12:26:10.255 [28658] <2> report_drives: FILE = /usr/openv/netbackup/db/media/tpreq/drive_IBM.ULTRIUM-TD3.000
12:26:10.255 [28658] <2> main: Sending [EXIT STATUS 0] to NBJM
12:26:10.255 [28658] <2> bptm: EXITING with status 0 <----------
12:29:46.393 [28743] <2> bptm: INITIATING (VERBOSE = 0): -delete_expired
12:29:46.403 [28743] <2> bptm: EXITING with status 0 <----------
12:29:46.894 [28747] <2> bptm: INITIATING (VERBOSE = 0): -delete_all_expired
12:29:46.904 [28747] <2> bptm: EMMserver_name = bckserver01
12:29:46.904 [28747] <2> bptm: EMMserver_port = 1556
12:29:46.966 [28747] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
12:29:46.966 [28747] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
12:29:46.976 [28747] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name bckserver01
12:29:47.080 [28747] <2> bptm: EXITING with status 0 <----------
12:36:10.199 [28913] <2> bptm: INITIATING (VERBOSE = 0): -rptdrv -jobid -1303437613 -jm
12:36:10.209 [28913] <2> bptm: EMMserver_name = bckserver01
12:36:10.209 [28913] <2> bptm: EMMserver_port = 1556
12:36:10.209 [28913] <2> drivename_open: Called with Create 0, file IBM.ULTRIUM-TD3.000
12:36:10.209 [28913] <2> drivename_checklock: Called
12:36:10.209 [28913] <2> drivename_checklock: PID 27396 has lock
12:36:10.210 [28913] <2> report_drives: MODE = 0
12:36:10.210 [28913] <2> report_drives: TIME = 1304393842
12:36:10.210 [28913] <2> report_drives: MASTER = bckserver01
12:36:10.210 [28913] <2> report_drives: PATH = /dev/rmt/0cbn
12:36:10.210 [28913] <2> report_drives: MEDIA = 0019L3
12:36:10.210 [28913] <2> report_drives: REQID = -1303437597
12:36:10.210 [28913] <2> report_drives: ALOCID = 89554
12:36:10.210 [28913] <2> report_drives: RBID = {A1298978-1DD1-11B2-8BF1-00144F8FEBCA}
12:36:10.210 [28913] <2> report_drives: PID = 27396
12:36:10.210 [28913] <2> report_drives: FILE = /usr/openv/netbackup/db/media/tpreq/drive_IBM.ULTRIUM-TD3.000
12:36:10.210 [28913] <2> main: Sending [EXIT STATUS 0] to NBJM
12:36:10.211 [28913] <2> bptm: EXITING with status 0 <----------
12:39:47.811 [29000] <2> bptm: INITIATING (VERBOSE = 0): -delete_expired
12:39:47.821 [29000] <2> bptm: EXITING with status 0 <----------
12:39:48.315 [29004] <2> bptm: INITIATING (VERBOSE = 0): -delete_all_expired
12:39:48.324 [29004] <2> bptm: EMMserver_name = bckserver01
12:39:48.324 [29004] <2> bptm: EMMserver_port = 1556
12:39:48.392 [29004] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
12:39:48.392 [29004] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
12:39:48.402 [29004] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name bckserver01
12:39:48.513 [29004] <2> bptm: EXITING with status 0 <----------
12:46:10.200 [29169] <2> bptm: INITIATING (VERBOSE = 0): -rptdrv -jobid -1303437616 -jm
12:46:10.210 [29169] <2> bptm: EMMserver_name = bckserver01
12:46:10.210 [29169] <2> bptm: EMMserver_port = 1556
12:46:10.210 [29169] <2> drivename_open: Called with Create 0, file IBM.ULTRIUM-TD3.000
12:46:10.210 [29169] <2> drivename_checklock: Called
12:46:10.210 [29169] <2> drivename_checklock: PID 27396 has lock
12:46:10.210 [29169] <2> report_drives: MODE = 0
12:46:10.211 [29169] <2> report_drives: TIME = 1304393842
12:46:10.211 [29169] <2> report_drives: MASTER = bckserver01
12:46:10.211 [29169] <2> report_drives: PATH = /dev/rmt/0cbn
12:46:10.211 [29169] <2> report_drives: MEDIA = 0019L3
12:46:10.211 [29169] <2> report_drives: REQID = -1303437597
12:46:10.211 [29169] <2> report_drives: ALOCID = 89554
12:46:10.211 [29169] <2> report_drives: RBID = {A1298978-1DD1-11B2-8BF1-00144F8FEBCA}
12:46:10.211 [29169] <2> report_drives: PID = 27396
12:46:10.211 [29169] <2> report_drives: FILE = /usr/openv/netbackup/db/media/tpreq/drive_IBM.ULTRIUM-TD3.000
12:46:10.211 [29169] <2> main: Sending [EXIT STATUS 0] to NBJM
12:46:10.212 [29169] <2> bptm: EXITING with status 0 <----------
12:49:49.216 [29254] <2> bptm: INITIATING (VERBOSE = 0): -delete_expired
12:49:49.225 [29254] <2> bptm: EXITING with status 0 <----------
12:49:49.712 [29258] <2> bptm: INITIATING (VERBOSE = 0): -delete_all_expired
12:49:49.722 [29258] <2> bptm: EMMserver_name = bckserver01
12:49:49.722 [29258] <2> bptm: EMMserver_port = 1556
12:49:49.784 [29258] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
12:49:49.784 [29258] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
12:49:49.793 [29258] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name bckserver01
12:49:49.901 [29258] <2> bptm: EXITING with status 0 <----------
12:56:10.241 [29425] <2> bptm: INITIATING (VERBOSE = 0): -rptdrv -jobid -1303437619 -jm
12:56:10.251 [29425] <2> bptm: EMMserver_name = bckserver01
12:56:10.251 [29425] <2> bptm: EMMserver_port = 1556
12:56:10.251 [29425] <2> drivename_open: Called with Create 0, file IBM.ULTRIUM-TD3.000
12:56:10.251 [29425] <2> drivename_checklock: Called
12:56:10.252 [29425] <2> drivename_checklock: PID 27396 has lock
12:56:10.252 [29425] <2> report_drives: MODE = 0
12:56:10.252 [29425] <2> report_drives: TIME = 1304393842
12:56:10.252 [29425] <2> report_drives: MASTER = bckserver01
12:56:10.252 [29425] <2> report_drives: PATH = /dev/rmt/0cbn
12:56:10.252 [29425] <2> report_drives: MEDIA = 0019L3
12:56:10.252 [29425] <2> report_drives: REQID = -1303437597
12:56:10.252 [29425] <2> report_drives: ALOCID = 89554
12:56:10.252 [29425] <2> report_drives: RBID = {A1298978-1DD1-11B2-8BF1-00144F8FEBCA}
12:56:10.252 [29425] <2> report_drives: PID = 27396
12:56:10.252 [29425] <2> report_drives: FILE = /usr/openv/netbackup/db/media/tpreq/drive_IBM.ULTRIUM-TD3.000
12:56:10.254 [29425] <2> main: Sending [EXIT STATUS 0] to NBJM
12:56:10.255 [29425] <2> bptm: EXITING with status 0 <----------
12:59:50.661 [29510] <2> bptm: INITIATING (VERBOSE = 0): -delete_expired
12:59:50.670 [29510] <2> bptm: EXITING with status 0 <----------
12:59:51.169 [29514] <2> bptm: INITIATING (VERBOSE = 0): -delete_all_expired
12:59:51.178 [29514] <2> bptm: EMMserver_name = bckserver01
12:59:51.178 [29514] <2> bptm: EMMserver_port = 1556
12:59:51.240 [29514] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
12:59:51.240 [29514] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
12:59:51.250 [29514] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name bckserver01
12:59:51.359 [29514] <2> bptm: EXITING with status 0 <----------
13:06:10.190 [29724] <2> bptm: INITIATING (VERBOSE = 0): -rptdrv -jobid -1303437622 -jm
13:06:10.199 [29724] <2> bptm: EMMserver_name = bckserver01
13:06:10.199 [29724] <2> bptm: EMMserver_port = 1556
13:06:10.199 [29724] <2> drivename_open: Called with Create 0, file IBM.ULTRIUM-TD3.000
13:06:10.200 [29724] <2> drivename_checklock: Called
13:06:10.200 [29724] <2> drivename_checklock: PID 27396 has lock
13:06:10.200 [29724] <2> report_drives: MODE = 0
13:06:10.200 [29724] <2> report_drives: TIME = 1304393842
13:06:10.200 [29724] <2> report_drives: MASTER = bckserver01
13:06:10.200 [29724] <2> report_drives: PATH = /dev/rmt/0cbn
13:06:10.200 [29724] <2> report_drives: MEDIA = 0019L3
13:06:10.200 [29724] <2> report_drives: REQID = -1303437597
13:06:10.200 [29724] <2> report_drives: ALOCID = 89554
13:06:10.200 [29724] <2> report_drives: RBID = {A1298978-1DD1-11B2-8BF1-00144F8FEBCA}
13:06:10.200 [29724] <2> report_drives: PID = 27396
13:06:10.200 [29724] <2> report_drives: FILE = /usr/openv/netbackup/db/media/tpreq/drive_IBM.ULTRIUM-TD3.000
13:06:10.201 [29724] <2> main: Sending [EXIT STATUS 0] to NBJM
13:06:10.201 [29724] <2> bptm: EXITING with status 0 <----------
13:09:52.090 [29807] <2> bptm: INITIATING (VERBOSE = 0): -delete_expired
13:09:52.100 [29807] <2> bptm: EXITING with status 0 <----------
13:09:52.610 [29811] <2> bptm: INITIATING (VERBOSE = 0): -delete_all_expired
13:09:52.619 [29811] <2> bptm: EMMserver_name = bckserver01
13:09:52.619 [29811] <2> bptm: EMMserver_port = 1556
13:09:52.686 [29811] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
13:09:52.686 [29811] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
13:09:52.696 [29811] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name bckserver01
13:09:52.801 [29811] <2> bptm: EXITING with status 0 <----------
13:16:10.162 [29975] <2> bptm: INITIATING (VERBOSE = 0): -rptdrv -jobid -1303437625 -jm
13:16:10.170 [29975] <2> bptm: EMMserver_name = bckserver01
13:16:10.171 [29975] <2> bptm: EMMserver_port = 1556
13:16:10.171 [29975] <2> drivename_open: Called with Create 0, file IBM.ULTRIUM-TD3.000
13:16:10.171 [29975] <2> drivename_checklock: Called
13:16:10.171 [29975] <2> drivename_checklock: PID 27396 has lock
13:16:10.171 [29975] <2> report_drives: MODE = 0
13:16:10.171 [29975] <2> report_drives: TIME = 1304393842
13:16:10.171 [29975] <2> report_drives: MASTER = bckserver01
13:16:10.171 [29975] <2> report_drives: PATH = /dev/rmt/0cbn
13:16:10.171 [29975] <2> report_drives: MEDIA = 0019L3
13:16:10.171 [29975] <2> report_drives: REQID = -1303437597
13:16:10.171 [29975] <2> report_drives: ALOCID = 89554
13:16:10.171 [29975] <2> report_drives: RBID = {A1298978-1DD1-11B2-8BF1-00144F8FEBCA}
13:16:10.171 [29975] <2> report_drives: PID = 27396
13:16:10.171 [29975] <2> report_drives: FILE = /usr/openv/netbackup/db/media/tpreq/drive_IBM.ULTRIUM-TD3.000
13:16:10.172 [29975] <2> main: Sending [EXIT STATUS 0] to NBJM
13:16:10.172 [29975] <2> bptm: EXITING with status 0 <----------
13:19:53.545 [66] <2> bptm: INITIATING (VERBOSE = 0): -delete_expired
13:19:53.554 [66] <2> bptm: EXITING with status 0 <----------
13:19:54.046 [70] <2> bptm: INITIATING (VERBOSE = 0): -delete_all_expired
13:19:54.055 [70] <2> bptm: EMMserver_name = bckserver01
13:19:54.055 [70] <2> bptm: EMMserver_port = 1556
13:19:54.119 [70] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
13:19:54.119 [70] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
13:19:54.128 [70] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name bckserver01
13:19:54.238 [70] <2> bptm: EXITING with status 0 <----------
13:26:10.201 [240] <2> bptm: INITIATING (VERBOSE = 0): -rptdrv -jobid -1303437628 -jm
13:26:10.211 [240] <2> bptm: EMMserver_name = bckserver01
13:26:10.211 [240] <2> bptm: EMMserver_port = 1556
13:26:10.212 [240] <2> drivename_open: Called with Create 0, file IBM.ULTRIUM-TD3.000
13:26:10.212 [240] <2> drivename_checklock: Called
13:26:10.212 [240] <2> drivename_checklock: PID 27396 has lock
13:26:10.212 [240] <2> report_drives: MODE = 0
13:26:10.212 [240] <2> report_drives: TIME = 1304393842
13:26:10.212 [240] <2> report_drives: MASTER = bckserver01
13:26:10.212 [240] <2> report_drives: PATH = /dev/rmt/0cbn
13:26:10.212 [240] <2> report_drives: MEDIA = 0019L3
13:26:10.212 [240] <2> report_drives: REQID = -1303437597
13:26:10.212 [240] <2> report_drives: ALOCID = 89554
13:26:10.212 [240] <2> report_drives: RBID = {A1298978-1DD1-11B2-8BF1-00144F8FEBCA}
13:26:10.212 [240] <2> report_drives: PID = 27396
13:26:10.212 [240] <2> report_drives: FILE = /usr/openv/netbackup/db/media/tpreq/drive_IBM.ULTRIUM-TD3.000
13:26:10.213 [240] <2> main: Sending [EXIT STATUS 0] to NBJM
13:26:10.213 [240] <2> bptm: EXITING with status 0 <----------
13:29:55.011 [338] <2> bptm: INITIATING (VERBOSE = 0): -delete_expired
13:29:55.021 [338] <2> bptm: EXITING with status 0 <----------
13:29:55.525 [343] <2> bptm: INITIATING (VERBOSE = 0): -delete_all_expired
13:29:55.535 [343] <2> bptm: EMMserver_name = bckserver01
13:29:55.535 [343] <2> bptm: EMMserver_port = 1556
13:29:55.602 [343] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
13:29:55.602 [343] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
13:29:55.612 [343] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name bckserver01
13:29:55.724 [343] <2> bptm: EXITING with status 0 <----------
13:36:10.159 [556] <2> bptm: INITIATING (VERBOSE = 0): -rptdrv -jobid -1303437631 -jm
13:36:10.169 [556] <2> bptm: EMMserver_name = bckserver01
13:36:10.169 [556] <2> bptm: EMMserver_port = 1556
13:36:10.169 [556] <2> drivename_open: Called with Create 0, file IBM.ULTRIUM-TD3.000
13:36:10.170 [556] <2> drivename_checklock: Called
13:36:10.170 [556] <2> drivename_checklock: PID 27396 has lock
13:36:10.170 [556] <2> report_drives: MODE = 0
13:36:10.170 [556] <2> report_drives: TIME = 1304393842
13:36:10.170 [556] <2> report_drives: MASTER = bckserver01
13:36:10.170 [556] <2> report_drives: PATH = /dev/rmt/0cbn
13:36:10.170 [556] <2> report_drives: MEDIA = 0019L3
13:36:10.170 [556] <2> report_drives: REQID = -1303437597
13:36:10.170 [556] <2> report_drives: ALOCID = 89554
13:36:10.170 [556] <2> report_drives: RBID = {A1298978-1DD1-11B2-8BF1-00144F8FEBCA}
13:36:10.170 [556] <2> report_drives: PID = 27396
13:36:10.170 [556] <2> report_drives: FILE = /usr/openv/netbackup/db/media/tpreq/drive_IBM.ULTRIUM-TD3.000
13:36:10.170 [556] <2> main: Sending [EXIT STATUS 0] to NBJM
13:36:10.171 [556] <2> bptm: EXITING with status 0 <----------
13:39:56.463 [646] <2> bptm: INITIATING (VERBOSE = 0): -delete_expired
13:39:56.473 [646] <2> bptm: EXITING with status 0 <----------
13:39:56.980 [650] <2> bptm: INITIATING (VERBOSE = 0): -delete_all_expired
13:39:56.989 [650] <2> bptm: EMMserver_name = bckserver01
13:39:56.989 [650] <2> bptm: EMMserver_port = 1556
13:39:57.066 [650] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
13:39:57.066 [650] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
13:39:57.076 [650] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name bckserver01
13:39:57.177 [650] <2> bptm: EXITING with status 0 <----------
13:46:10.237 [827] <2> bptm: INITIATING (VERBOSE = 0): -rptdrv -jobid -1303437634 -jm
13:46:10.247 [827] <2> bptm: EMMserver_name = bckserver01
13:46:10.247 [827] <2> bptm: EMMserver_port = 1556
13:46:10.248 [827] <2> drivename_open: Called with Create 0, file IBM.ULTRIUM-TD3.000
13:46:10.248 [827] <2> drivename_checklock: Called
13:46:10.248 [827] <2> drivename_checklock: PID 27396 has lock
13:46:10.248 [827] <2> report_drives: MODE = 0
13:46:10.248 [827] <2> report_drives: TIME = 1304393842
13:46:10.248 [827] <2> report_drives: MASTER = bckserver01
13:46:10.248 [827] <2> report_drives: PATH = /dev/rmt/0cbn
13:46:10.248 [827] <2> report_drives: MEDIA = 0019L3
13:46:10.248 [827] <2> report_drives: REQID = -1303437597
13:46:10.248 [827] <2> report_drives: ALOCID = 89554
13:46:10.248 [827] <2> report_drives: RBID = {A1298978-1DD1-11B2-8BF1-00144F8FEBCA}
13:46:10.248 [827] <2> report_drives: PID = 27396
13:46:10.248 [827] <2> report_drives: FILE = /usr/openv/netbackup/db/media/tpreq/drive_IBM.ULTRIUM-TD3.000
13:46:10.249 [827] <2> main: Sending [EXIT STATUS 0] to NBJM
13:46:10.249 [827] <2> bptm: EXITING with status 0 <----------
13:49:57.918 [954] <2> bptm: INITIATING (VERBOSE = 0): -delete_expired
13:49:57.927 [954] <2> bptm: EXITING with status 0 <----------
13:49:58.408 [958] <2> bptm: INITIATING (VERBOSE = 0): -delete_all_expired
13:49:58.417 [958] <2> bptm: EMMserver_name = bckserver01
13:49:58.417 [958] <2> bptm: EMMserver_port = 1556
13:49:58.480 [958] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
13:49:58.481 [958] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
13:49:58.491 [958] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name bckserver01
13:49:58.599 [958] <2> bptm: EXITING with status 0 <----------
13:56:10.214 [1111] <2> bptm: INITIATING (VERBOSE = 0): -rptdrv -jobid -1303437637 -jm
13:56:10.224 [1111] <2> bptm: EMMserver_name = bckserver01
13:56:10.224 [1111] <2> bptm: EMMserver_port = 1556
13:56:10.225 [1111] <2> drivename_open: Called with Create 0, file IBM.ULTRIUM-TD3.000
13:56:10.225 [1111] <2> drivename_checklock: Called
13:56:10.225 [1111] <2> drivename_checklock: PID 27396 has lock
13:56:10.225 [1111] <2> report_drives: MODE = 0
13:56:10.225 [1111] <2> report_drives: TIME = 1304393842
13:56:10.225 [1111] <2> report_drives: MASTER = bckserver01
13:56:10.225 [1111] <2> report_drives: PATH = /dev/rmt/0cbn
13:56:10.225 [1111] <2> report_drives: MEDIA = 0019L3
13:56:10.225 [1111] <2> report_drives: REQID = -1303437597
13:56:10.225 [1111] <2> report_drives: ALOCID = 89554
13:56:10.225 [1111] <2> report_drives: RBID = {A1298978-1DD1-11B2-8BF1-00144F8FEBCA}
13:56:10.225 [1111] <2> report_drives: PID = 27396
13:56:10.225 [1111] <2> report_drives: FILE = /usr/openv/netbackup/db/media/tpreq/drive_IBM.ULTRIUM-TD3.000
13:56:10.226 [1111] <2> main: Sending [EXIT STATUS 0] to NBJM
13:56:10.226 [1111] <2> bptm: EXITING with status 0 <----------
13:59:59.314 [1207] <2> bptm: INITIATING (VERBOSE = 0): -delete_expired
13:59:59.324 [1207] <2> bptm: EXITING with status 0 <----------
13:59:59.817 [1211] <2> bptm: INITIATING (VERBOSE = 0): -delete_all_expired
13:59:59.827 [1211] <2> bptm: EMMserver_name = bckserver01
13:59:59.827 [1211] <2> bptm: EMMserver_port = 1556
13:59:59.888 [1211] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
13:59:59.888 [1211] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
13:59:59.897 [1211] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name bckserver01
14:00:00.001 [1211] <2> bptm: EXITING with status 0 <----------
14:06:10.204 [1376] <2> bptm: INITIATING (VERBOSE = 0): -rptdrv -jobid -1303437640 -jm
14:06:10.215 [1376] <2> bptm: EMMserver_name = bckserver01
14:06:10.215 [1376] <2> bptm: EMMserver_port = 1556
14:06:10.216 [1376] <2> drivename_open: Called with Create 0, file IBM.ULTRIUM-TD3.000
14:06:10.216 [1376] <2> drivename_checklock: Called
14:06:10.216 [1376] <2> drivename_checklock: PID 27396 has lock
14:06:10.216 [1376] <2> report_drives: MODE = 0
14:06:10.216 [1376] <2> report_drives: TIME = 1304393842
14:06:10.216 [1376] <2> report_drives: MASTER = bckserver01
14:06:10.216 [1376] <2> report_drives: PATH = /dev/rmt/0cbn
14:06:10.216 [1376] <2> report_drives: MEDIA = 0019L3
14:06:10.216 [1376] <2> report_drives: REQID = -1303437597
14:06:10.216 [1376] <2> report_drives: ALOCID = 89554
14:06:10.216 [1376] <2> report_drives: RBID = {A1298978-1DD1-11B2-8BF1-00144F8FEBCA}
14:06:10.216 [1376] <2> report_drives: PID = 27396
14:06:10.216 [1376] <2> report_drives: FILE = /usr/openv/netbackup/db/media/tpreq/drive_IBM.ULTRIUM-TD3.000
14:06:10.219 [1376] <2> main: Sending [EXIT STATUS 0] to NBJM
14:06:10.219 [1376] <2> bptm: EXITING with status 0 <----------
14:10:00.759 [1464] <2> bptm: INITIATING (VERBOSE = 0): -delete_expired
14:10:00.768 [1464] <2> bptm: EXITING with status 0 <----------
14:10:01.258 [1483] <2> bptm: INITIATING (VERBOSE = 0): -delete_all_expired
14:10:01.267 [1483] <2> bptm: EMMserver_name = bckserver01
14:10:01.267 [1483] <2> bptm: EMMserver_port = 1556
14:10:01.330 [1483] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
14:10:01.330 [1483] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
14:10:01.339 [1483] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name bckserver01
14:10:01.444 [1483] <2> bptm: EXITING with status 0 <----------
14:16:10.243 [1633] <2> bptm: INITIATING (VERBOSE = 0): -rptdrv -jobid -1303437643 -jm
14:16:10.252 [1633] <2> bptm: EMMserver_name = bckserver01
14:16:10.252 [1633] <2> bptm: EMMserver_port = 1556
14:16:10.252 [1633] <2> drivename_open: Called with Create 0, file IBM.ULTRIUM-TD3.000
14:16:10.252 [1633] <2> drivename_checklock: Called
14:16:10.252 [1633] <2> drivename_checklock: PID 27396 has lock
14:16:10.253 [1633] <2> report_drives: MODE = 0
14:16:10.253 [1633] <2> report_drives: TIME = 1304393842
14:16:10.253 [1633] <2> report_drives: MASTER = bckserver01
14:16:10.253 [1633] <2> report_drives: PATH = /dev/rmt/0cbn
14:16:10.253 [1633] <2> report_drives: MEDIA = 0019L3
14:16:10.253 [1633] <2> report_drives: REQID = -1303437597
14:16:10.253 [1633] <2> report_drives: ALOCID = 89554
14:16:10.253 [1633] <2> report_drives: RBID = {A1298978-1DD1-11B2-8BF1-00144F8FEBCA}
14:16:10.253 [1633] <2> report_drives: PID = 27396
14:16:10.253 [1633] <2> report_drives: FILE = /usr/openv/netbackup/db/media/tpreq/drive_IBM.ULTRIUM-TD3.000
14:16:10.254 [1633] <2> main: Sending [EXIT STATUS 0] to NBJM
14:16:10.254 [1633] <2> bptm: EXITING with status 0 <----------
14:20:02.166 [1933] <2> bptm: INITIATING (VERBOSE = 0): -delete_expired
14:20:02.175 [1933] <2> bptm: EXITING with status 0 <----------
14:20:02.667 [1937] <2> bptm: INITIATING (VERBOSE = 0): -delete_all_expired
14:20:02.676 [1937] <2> bptm: EMMserver_name = bckserver01
14:20:02.676 [1937] <2> bptm: EMMserver_port = 1556
14:20:02.741 [1937] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
14:20:02.741 [1937] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
14:20:02.751 [1937] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name bckserver01
14:20:02.860 [1937] <2> bptm: EXITING with status 0 <----------
14:26:10.247 [2110] <2> bptm: INITIATING (VERBOSE = 0): -rptdrv -jobid -1303437646 -jm
14:26:10.257 [2110] <2> bptm: EMMserver_name = bckserver01
14:26:10.257 [2110] <2> bptm: EMMserver_port = 1556
14:26:10.258 [2110] <2> drivename_open: Called with Create 0, file IBM.ULTRIUM-TD3.000
14:26:10.258 [2110] <2> drivename_checklock: Called
14:26:10.258 [2110] <2> drivename_checklock: PID 27396 has lock
14:26:10.258 [2110] <2> report_drives: MODE = 0
14:26:10.258 [2110] <2> report_drives: TIME = 1304393842
14:26:10.258 [2110] <2> report_drives: MASTER = bckserver01
14:26:10.258 [2110] <2> report_drives: PATH = /dev/rmt/0cbn
14:26:10.258 [2110] <2> report_drives: MEDIA = 0019L3
14:26:10.258 [2110] <2> report_drives: REQID = -1303437597
14:26:10.258 [2110] <2> report_drives: ALOCID = 89554
14:26:10.258 [2110] <2> report_drives: RBID = {A1298978-1DD1-11B2-8BF1-00144F8FEBCA}
14:26:10.258 [2110] <2> report_drives: PID = 27396
14:26:10.258 [2110] <2> report_drives: FILE = /usr/openv/netbackup/db/media/tpreq/drive_IBM.ULTRIUM-TD3.000
14:26:10.259 [2110] <2> main: Sending [EXIT STATUS 0] to NBJM
14:26:10.259 [2110] <2> bptm: EXITING with status 0 <----------
 

bpbrm log on mediaserver:

11:37:13.585 [27395] <2> logparams: -backup -S bckserver01 -c 10.67.99.227 -ct 13 -ru root -cl File_10.67.99.227 -sched Full -bt 1304393831 -dt 0 -st 0 -b 10.67.99.227_1304393831 -mediasvr bckserver01 -jobid 147385 -jobgrpid 147385 -masterversion 600000 -maxfrag 1048576 -reqid -1303437597 -mt 2 -to 0 -stunit bckserver01-hcart2-robot-tld-0 -cj 2 -rl 1 -rp 1209600 -D 14 -rt 8 -rn 0 -pool NetBackup -use_ofb -use_otm -WOFB_enabled 1 -nbu -jm -secure 1 -rg root -fso
11:37:13.611 [27395] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2034: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006
11:37:13.611 [27395] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2048: service: bpcd
11:37:13.719 [27395] <2> logconnections: BPCD CONNECT FROM 10.87.0.198.58871 TO 10.67.99.227.13724
11:37:13.720 [27395] <2> vnet_connect_to_vnetd_extra: vnet_vnetd.c.178: msg: VNETD CONNECT FROM 10.87.0.198.58873 TO 10.67.99.227.13724 fd = 7
11:37:13.733 [27395] <2> vnet_vnetd_connect_forward_socket_end: vnet_vnetd.c.529: VN_REQUEST_CONNECT_FORWARD_SOCKET: 10 0x0000000a
11:37:13.919 [27395] <2> vnet_vnetd_connect_forward_socket_end: vnet_vnetd.c.546: ipc_string: 4151
11:37:14.232 [27395] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:37:14.232 [27395] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:37:14.285 [27395] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:37:14.285 [27395] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:37:14.334 [27395] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2034: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006
11:37:14.334 [27395] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2048: service: bpcd
11:37:14.626 [27395] <2> logconnections: BPCD CONNECT FROM 10.87.0.198.58880 TO 10.67.99.227.13724
11:37:14.626 [27395] <2> vnet_connect_to_vnetd_extra: vnet_vnetd.c.178: msg: VNETD CONNECT FROM 10.87.0.198.58881 TO 10.67.99.227.13724 fd = 7
11:37:14.639 [27395] <2> vnet_vnetd_connect_forward_socket_end: vnet_vnetd.c.529: VN_REQUEST_CONNECT_FORWARD_SOCKET: 10 0x0000000a
11:37:14.824 [27395] <2> vnet_vnetd_connect_forward_socket_end: vnet_vnetd.c.546: ipc_string: 4153
11:37:15.131 [27395] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:37:15.131 [27395] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:37:15.153 [27395] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:37:15.153 [27395] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:37:15.220 [27395] <2> local_getAllBEorNBHostInfo: CLIENT_CMD_SOCK from bpcr = 6
11:37:15.320 [27395] <2> bpcr_get_version_rqst: bpcd version: 06000404
11:37:15.530 [27395] <2> local_getAllBEorNBHostInfo: hostname comparison failed
11:37:15.630 [27395] <2> bpcr_get_version_rqst: bpcd version: 06000404
11:37:15.630 [27395] <2> local_getAllBEorNBHostInfo: rqstType == BPCD_GET_NB_VERSION_RQST
11:37:16.131 [27395] <2> bpcr_get_platform_rqst: Server client platform length = 2
11:37:16.230 [27395] <2> xlatePlatformName: my version = 600000, client version = 600000, check current list first
11:37:16.230 [27395] <2> xlatePlatformName: BUILD_PLATFORM name to xlate = nt
11:37:16.230 [27395] <2> local_getAllBEorNBHostInfo: platform of client 10.67.99.227 is PC, Windows2000
11:37:16.430 [27395] <2> bpcr_get_uname_rqst: bpcr.c: 3754 count=27, buffer=UNAME_SYSNAME = WindowsNET
11:37:16.430 [27395] <2> bpcr_get_uname_rqst: bpcr.c: 3754 count=23, buffer=UNAME_NODENAME = CCCCE
11:37:16.430 [27395] <2> bpcr_get_uname_rqst: bpcr.c: 3754 count=18, buffer=UNAME_RELEASE = 5
11:37:16.430 [27395] <2> bpcr_get_uname_rqst: bpcr.c: 3754 count=18, buffer=UNAME_VERSION = 2
11:37:16.430 [27395] <2> bpcr_get_uname_rqst: bpcr.c: 3754 count=62, buffer=UNAME_MACHINE = Intel x86 - Intel Pentium Pro - Revision xf06
11:37:16.430 [27395] <2> bpcr_get_uname_rqst: bpcr.c: 3754 count=3, buffer=END
11:37:16.430 [27395] <2> local_getAllBEorNBHostInfo: dbmisc.c: 1640, uname -sr=WindowsNET 5

11:37:16.535 [27395] <2> bpcr_get_version_rqst: bpcd version: 06000404
11:37:17.030 [27395] <2> getPatchLevel: CLIENT_CMD_SOCK from bpcr = 6
11:37:17.138 [27395] <2> getPatchLevel: got client socket
11:37:17.230 [27395] <2> getPatchLevel: file num = 4, file size = 112
11:37:17.230 [27395] <2> bpcr_read_rqst: completed send of file number 4
11:37:17.230 [27395] <2> bpcr_read_rqst: completed send of transfer_count = 16383
11:37:17.230 [27395] <2> bpcr_read_rqst: Before readclientstatus on socket 7
11:37:17.358 [27395] <2> bpcr_read_rqst: Num bytes to be sent by client = 112
11:37:17.450 [27395] <2> getPatchLevel: total chars read = 112
11:37:17.450 [27395] <2> getPatchLevel: level = >6.0MP4<
11:37:17.904 [27395] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2034: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006
11:37:17.904 [27395] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2048: service: bpcd
11:37:18.062 [27395] <2> logconnections: BPCD CONNECT FROM 10.87.0.198.58896 TO 10.67.99.227.13724
11:37:18.063 [27395] <2> vnet_connect_to_vnetd_extra: vnet_vnetd.c.178: msg: VNETD CONNECT FROM 10.87.0.198.58897 TO 10.67.99.227.13724 fd = 7
11:37:18.080 [27395] <2> vnet_vnetd_connect_forward_socket_end: vnet_vnetd.c.529: VN_REQUEST_CONNECT_FORWARD_SOCKET: 10 0x0000000a
11:37:18.246 [27395] <2> vnet_vnetd_connect_forward_socket_end: vnet_vnetd.c.546: ipc_string: 4156
11:37:18.451 [27395] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:37:18.452 [27395] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:37:18.473 [27395] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:37:18.473 [27395] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:37:18.640 [27395] <2> bpcr_get_version_rqst: bpcd version: 06000404
11:37:18.740 [27395] <2> bpcr_get_platform_rqst: Server client platform length = 2
11:37:18.740 [27395] <2> bpcr_check_for_use_ofb_support: bpcd platform nt
 

bpcm log on media server:

11:37:13.093 [27393] <2> bpcd main: offset to GMT -28800
11:37:13.093 [27393] <2> logconnections: BPCD ACCEPT FROM 10.87.0.198.58861 TO 10.87.0.198.13724
11:37:13.093 [27393] <2> bpcd main: setup_sockopts complete
11:37:13.102 [27393] <2> bpcd peer_hostname: Connection from host bckserver01 (10.87.0.198) port 58861
11:37:13.111 [27393] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:37:13.111 [27393] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:37:13.137 [27393] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:37:13.138 [27393] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:37:13.139 [27393] <2> bpcd valid_server: comparing bckserver01 and bckserver01
11:37:13.139 [27393] <4> bpcd valid_server: hostname comparison succeeded
11:37:13.140 [27393] <2> bpcd main: output socket port number = 1
11:37:13.194 [27393] <2> bpcd main: Duplicated vnetd socket on stderr
11:37:13.194 [27393] <2> bpcd main: <---- NetBackup 6.0 0 ------------initiated
11:37:13.247 [27393] <2> bpcd main: Not using VxSS authentication with bckserver01
11:37:13.293 [27393] <2> bpcd main: BPCD_FORK_CMD_RQST
11:37:13.340 [27393] <2> bpcd main: fork cmd = /usr/openv/netbackup/bin/bpbrm bpbrm -backup -S bckserver01 -c 10.67.99.227 -ct 13 -ru root -cl File_10.67.99.227 -sched Full -bt 1304393831 -dt 0 -st 0 -b 10.67.99.227_1304393831 -mediasvr bckserver01 -jobid 147385 -jobgrpid 147385 -masterversion 600000 -maxfrag 1048576 -reqid -1303437597 -mt 2 -to 0 -stunit bckserver01-hcart2-robot-tld-0 -cj 2 -rl 1 -rp 1209600 -D 14 -rt 8 -rn 0 -pool NetBackup -use_ofb -use_otm -WOFB_enabled 1 -nbu -jm -secure 1 -rg root -fso
11:37:13.340 [27393] <2> bpcd main: filter type = -1
11:37:13.340 [27393] <2> bpcd main: backuptime before fork = 0
11:37:13.341 [27393] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
11:37:13.341 [27395] <2> bpcd main: Converting args string into an argv
11:37:13.341 [27395] <2> bpcd main: Done converting args string into an argv
11:37:13.341 [27395] <2> bpcd main: child_args[0] = /usr/openv/netbackup/bin/bpbrm
11:37:13.341 [27395] <2> bpcd main: child_args[1] = bpbrm
11:37:13.341 [27395] <2> bpcd main: child_args[2] = -backup
11:37:13.341 [27395] <2> bpcd main: child_args[3] = -S
11:37:13.341 [27395] <2> bpcd main: child_args[4] = bckserver01
11:37:13.341 [27395] <2> bpcd main: child_args[5] = -c
11:37:13.341 [27395] <2> bpcd main: child_args[6] = 10.67.99.227
11:37:13.341 [27395] <2> bpcd main: child_args[7] = -ct
11:37:13.341 [27395] <2> bpcd main: child_args[8] = 13
11:37:13.342 [27395] <2> bpcd main: child_args[9] = -ru
11:37:13.342 [27395] <2> bpcd main: child_args[10] = root
11:37:13.342 [27395] <2> bpcd main: child_args[11] = -cl
11:37:13.342 [27395] <2> bpcd main: child_args[12] = File_10.67.99.227
11:37:13.342 [27395] <2> bpcd main: child_args[13] = -sched
11:37:13.342 [27395] <2> bpcd main: child_args[14] = Full
11:37:13.342 [27395] <2> bpcd main: child_args[15] = -bt
11:37:13.342 [27395] <2> bpcd main: child_args[16] = 1304393831
11:37:13.342 [27395] <2> bpcd main: child_args[17] = -dt
11:37:13.342 [27395] <2> bpcd main: child_args[18] = 0
11:37:13.342 [27395] <2> bpcd main: child_args[19] = -st
11:37:13.342 [27395] <2> bpcd main: child_args[20] = 0
11:37:13.342 [27395] <2> bpcd main: child_args[21] = -b
11:37:13.342 [27395] <2> bpcd main: child_args[22] = 10.67.99.227_1304393831
11:37:13.342 [27395] <2> bpcd main: child_args[23] = -mediasvr
11:37:13.342 [27395] <2> bpcd main: child_args[24] = bckserver01
11:37:13.342 [27395] <2> bpcd main: child_args[25] = -jobid
11:37:13.342 [27395] <2> bpcd main: child_args[26] = 147385
11:37:13.342 [27395] <2> bpcd main: child_args[27] = -jobgrpid
11:37:13.342 [27395] <2> bpcd main: child_args[28] = 147385
11:37:13.342 [27395] <2> bpcd main: child_args[29] = -masterversion
11:37:13.342 [27395] <2> bpcd main: child_args[30] = 600000
11:37:13.342 [27395] <2> bpcd main: child_args[31] = -maxfrag
11:37:13.342 [27395] <2> bpcd main: child_args[32] = 1048576
11:37:13.342 [27395] <2> bpcd main: child_args[33] = -reqid
11:37:13.342 [27395] <2> bpcd main: child_args[34] = -1303437597
11:37:13.342 [27395] <2> bpcd main: child_args[35] = -mt
11:37:13.342 [27395] <2> bpcd main: child_args[36] = 2
11:37:13.342 [27395] <2> bpcd main: child_args[37] = -to
11:37:13.342 [27395] <2> bpcd main: child_args[38] = 0
11:37:13.342 [27395] <2> bpcd main: child_args[39] = -stunit
11:37:13.342 [27395] <2> bpcd main: child_args[40] = bckserver01-hcart2-robot-tld-0
11:37:13.342 [27395] <2> bpcd main: child_args[41] = -cj
11:37:13.342 [27395] <2> bpcd main: child_args[42] = 2
11:37:13.342 [27395] <2> bpcd main: child_args[43] = -rl
11:37:13.342 [27395] <2> bpcd main: child_args[44] = 1
11:37:13.342 [27395] <2> bpcd main: child_args[45] = -rp
11:37:13.342 [27395] <2> bpcd main: child_args[46] = 1209600
11:37:13.342 [27395] <2> bpcd main: child_args[47] = -D
11:37:13.342 [27395] <2> bpcd main: child_args[48] = 14
11:37:13.342 [27395] <2> bpcd main: child_args[49] = -rt
11:37:13.342 [27395] <2> bpcd main: child_args[50] = 8
11:37:13.342 [27395] <2> bpcd main: child_args[51] = -rn
11:37:13.342 [27395] <2> bpcd main: child_args[52] = 0
11:37:13.342 [27395] <2> bpcd main: child_args[53] = -pool
11:37:13.342 [27395] <2> bpcd main: child_args[54] = NetBackup
11:37:13.342 [27395] <2> bpcd main: child_args[55] = -use_ofb
11:37:13.342 [27395] <2> bpcd main: child_args[56] = -use_otm
11:37:13.342 [27395] <2> bpcd main: child_args[57] = -WOFB_enabled
11:37:13.342 [27395] <2> bpcd main: child_args[58] = 1
11:37:13.342 [27395] <2> bpcd main: child_args[59] = -nbu
11:37:13.342 [27395] <2> bpcd main: child_args[60] = -jm
11:37:13.342 [27395] <2> bpcd main: child_args[61] = -secure
11:37:13.342 [27395] <2> bpcd main: child_args[62] = 1
11:37:13.342 [27395] <2> bpcd main: child_args[63] = -rg
11:37:13.342 [27395] <2> bpcd main: child_args[64] = root
11:37:13.342 [27395] <2> bpcd main: child_args[65] = -fso
11:37:13.342 [27395] <2> bpcd main: Before execvp of command
11:37:40.073 [27409] <2> bpcd main: offset to GMT -28800
11:37:40.074 [27409] <2> logconnections: BPCD ACCEPT FROM 10.87.0.198.58954 TO 10.87.0.198.13724
11:37:40.074 [27409] <2> bpcd main: setup_sockopts complete
11:37:40.083 [27409] <2> bpcd peer_hostname: Connection from host bckserver01 (10.87.0.198) port 58954
11:37:40.092 [27409] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:37:40.092 [27409] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:37:40.121 [27409] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:37:40.121 [27409] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:37:40.122 [27409] <2> bpcd valid_server: comparing bckserver01 and bckserver01
11:37:40.122 [27409] <4> bpcd valid_server: hostname comparison succeeded
11:37:40.123 [27409] <2> bpcd main: output socket port number = 1
11:37:40.127 [27409] <2> bpcd main: Duplicated vnetd socket on stderr
11:37:40.127 [27409] <2> bpcd main: <---- NetBackup 6.0 0 ------------initiated
11:37:40.170 [27409] <2> bpcd main: Not using VxSS authentication with bckserver01
11:37:40.220 [27409] <2> bpcd main: BPCD_GET_PROCESS_STATUS_RQST
11:37:40.270 [27409] <2> find_processes: total malloc-ed = 4096
11:37:40.282 [27409] <2> get_unxwre_processes: 119 files considered, 117 files opened/read
11:37:40.282 [27409] <2> find_processes: progs_list_size = 4096 == total calculated size = 4096 == (total_space_used = 4031 + whats_left = 65)
11:37:40.282 [27409] <2> find_processes: realloc down to 4031
11:37:40.330 [27409] <2> bpcd main: BPCD_DISCONNECT_RQST
11:37:40.330 [27409] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
11:38:41.744 [27432] <2> bpcd main: offset to GMT -28800
11:38:41.744 [27432] <2> logconnections: BPCD ACCEPT FROM 10.87.0.198.59031 TO 10.87.0.198.13724
11:38:41.744 [27432] <2> bpcd main: setup_sockopts complete
11:38:41.753 [27432] <2> bpcd peer_hostname: Connection from host bckserver01 (10.87.0.198) port 59031
11:38:41.762 [27432] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:38:41.762 [27432] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:38:41.791 [27432] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:38:41.791 [27432] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:38:41.792 [27432] <2> bpcd valid_server: comparing bckserver01 and bckserver01
11:38:41.793 [27432] <4> bpcd valid_server: hostname comparison succeeded
11:38:41.793 [27432] <2> bpcd main: output socket port number = 1
11:38:41.795 [27432] <2> bpcd main: Duplicated vnetd socket on stderr
11:38:41.795 [27432] <2> bpcd main: <---- NetBackup 6.0 0 ------------initiated
11:38:41.841 [27432] <2> bpcd main: Not using VxSS authentication with bckserver01
11:38:41.890 [27432] <2> bpcd main: BPCD_GET_PROCESS_STATUS_RQST
11:38:41.940 [27432] <2> find_processes: total malloc-ed = 4096
11:38:41.953 [27432] <2> get_unxwre_processes: 118 files considered, 116 files opened/read
11:38:41.953 [27432] <2> find_processes: progs_list_size = 4096 == total calculated size = 4096 == (total_space_used = 3971 + whats_left = 125)
11:38:41.953 [27432] <2> find_processes: realloc down to 3971
11:38:42.003 [27432] <2> bpcd main: BPCD_DISCONNECT_RQST
11:38:42.003 [27432] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
11:39:38.884 [27453] <2> bpcd main: offset to GMT -28800
11:39:38.884 [27453] <2> logconnections: BPCD ACCEPT FROM 10.87.0.198.59098 TO 10.87.0.198.13724
11:39:38.884 [27453] <2> bpcd main: setup_sockopts complete
11:39:38.893 [27453] <2> bpcd peer_hostname: Connection from host bckserver01 (10.87.0.198) port 59098
11:39:38.901 [27453] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:39:38.901 [27453] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:39:38.927 [27453] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:39:38.927 [27453] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:39:38.929 [27453] <2> bpcd valid_server: comparing bckserver01 and bckserver01
11:39:38.929 [27453] <4> bpcd valid_server: hostname comparison succeeded
11:39:38.930 [27453] <2> bpcd main: output socket port number = 1
11:39:38.931 [27453] <2> bpcd main: Duplicated vnetd socket on stderr
11:39:38.931 [27453] <2> bpcd main: <---- NetBackup 6.0 0 ------------initiated
11:39:38.971 [27453] <2> bpcd main: Not using VxSS authentication with bckserver01
11:39:39.021 [27453] <2> bpcd main: BPCD_IMMED_CMD_RQST
11:39:39.021 [27453] <2> bpcd main: immed cmd = /usr/openv/netbackup/bin/bptm -delete_expired 1>/dev/null 2>/dev/null
11:39:39.021 [27453] <2> sanitary_mb_str: detected /dev/null after redirection
11:39:39.021 [27453] <2> sanitary_mb_str: detected /dev/null after redirection
11:39:39.021 [27453] <2> sanitary_mb_str: String "/usr/openv/netbackup/bin/bptm -delete_expired 1>/dev/null 2>/dev/null" is considered sanitary.
11:39:39.272 [27453] <2> bpcd main: completed command
11:39:39.273 [27453] <2> bpcd main: immed cmd status = 0x0
11:39:39.273 [27453] <2> bpcd main: immed cmd status = 0
11:39:39.273 [27453] <2> bpcd main: BPCD_DISCONNECT_RQST
11:39:39.273 [27453] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
11:39:39.371 [27457] <2> bpcd main: offset to GMT -28800
11:39:39.371 [27457] <2> logconnections: BPCD ACCEPT FROM 10.87.0.198.59107 TO 10.87.0.198.13724
11:39:39.371 [27457] <2> bpcd main: setup_sockopts complete
11:39:39.380 [27457] <2> bpcd peer_hostname: Connection from host bckserver01 (10.87.0.198) port 59107
11:39:39.388 [27457] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:39:39.388 [27457] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:39:39.416 [27457] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:39:39.416 [27457] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:39:39.417 [27457] <2> bpcd valid_server: comparing bckserver01 and bckserver01
11:39:39.418 [27457] <4> bpcd valid_server: hostname comparison succeeded
11:39:39.418 [27457] <2> bpcd main: output socket port number = 1
11:39:39.420 [27457] <2> bpcd main: Duplicated vnetd socket on stderr
11:39:39.420 [27457] <2> bpcd main: <---- NetBackup 6.0 0 ------------initiated
11:39:39.461 [27457] <2> bpcd main: Not using VxSS authentication with bckserver01
11:39:39.511 [27457] <2> bpcd main: BPCD_IMMED_CMD_RQST
11:39:39.511 [27457] <2> bpcd main: immed cmd = /usr/openv/netbackup/bin/bptm -delete_all_expired 1>/dev/null 2>/dev/null
11:39:39.511 [27457] <2> sanitary_mb_str: detected /dev/null after redirection
11:39:39.511 [27457] <2> sanitary_mb_str: detected /dev/null after redirection
11:39:39.511 [27457] <2> sanitary_mb_str: String "/usr/openv/netbackup/bin/bptm -delete_all_expired 1>/dev/null 2>/dev/null" is considered sanitary.
11:39:39.951 [27457] <2> bpcd main: completed command
11:39:39.951 [27457] <2> bpcd main: immed cmd status = 0x0
11:39:39.951 [27457] <2> bpcd main: immed cmd status = 0
11:39:39.952 [27457] <2> bpcd main: BPCD_DISCONNECT_RQST
11:39:39.952 [27457] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
11:39:43.424 [27464] <2> bpcd main: offset to GMT -28800
11:39:43.424 [27464] <2> logconnections: BPCD ACCEPT FROM 10.87.0.198.59126 TO 10.87.0.198.13724
11:39:43.424 [27464] <2> bpcd main: setup_sockopts complete
11:39:43.434 [27464] <2> bpcd peer_hostname: Connection from host bckserver01 (10.87.0.198) port 59126
11:39:43.442 [27464] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:39:43.442 [27464] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:39:43.471 [27464] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:39:43.471 [27464] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:39:43.473 [27464] <2> bpcd valid_server: comparing bckserver01 and bckserver01
11:39:43.473 [27464] <4> bpcd valid_server: hostname comparison succeeded
11:39:43.473 [27464] <2> bpcd main: output socket port number = 1
11:39:43.475 [27464] <2> bpcd main: Duplicated vnetd socket on stderr
11:39:43.475 [27464] <2> bpcd main: <---- NetBackup 6.0 0 ------------initiated
11:39:43.521 [27464] <2> bpcd main: Not using VxSS authentication with bckserver01
11:39:43.571 [27464] <2> bpcd main: BPCD_GET_PROCESS_STATUS_RQST
11:39:43.621 [27464] <2> find_processes: total malloc-ed = 4096
11:39:43.634 [27464] <2> get_unxwre_processes: 118 files considered, 116 files opened/read
11:39:43.634 [27464] <2> find_processes: progs_list_size = 4096 == total calculated size = 4096 == (total_space_used = 3971 + whats_left = 125)
11:39:43.634 [27464] <2> find_processes: realloc down to 3971
11:39:43.681 [27464] <2> bpcd main: BPCD_DISCONNECT_RQST
11:39:43.681 [27464] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
11:40:45.096 [27487] <2> bpcd main: offset to GMT -28800
11:40:45.096 [27487] <2> logconnections: BPCD ACCEPT FROM 10.87.0.198.59203 TO 10.87.0.198.13724
11:40:45.096 [27487] <2> bpcd main: setup_sockopts complete
11:40:45.106 [27487] <2> bpcd peer_hostname: Connection from host bckserver01 (10.87.0.198) port 59203
11:40:45.114 [27487] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:40:45.114 [27487] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:40:45.143 [27487] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:40:45.143 [27487] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:40:45.144 [27487] <2> bpcd valid_server: comparing bckserver01 and bckserver01
11:40:45.144 [27487] <4> bpcd valid_server: hostname comparison succeeded
11:40:45.145 [27487] <2> bpcd main: output socket port number = 1
11:40:45.149 [27487] <2> bpcd main: Duplicated vnetd socket on stderr
11:40:45.149 [27487] <2> bpcd main: <---- NetBackup 6.0 0 ------------initiated
11:40:45.192 [27487] <2> bpcd main: Not using VxSS authentication with bckserver01
11:40:45.242 [27487] <2> bpcd main: BPCD_GET_PROCESS_STATUS_RQST
11:40:45.291 [27487] <2> find_processes: total malloc-ed = 4096
11:40:45.305 [27487] <2> get_unxwre_processes: 118 files considered, 116 files opened/read
11:40:45.306 [27487] <2> find_processes: progs_list_size = 4096 == total calculated size = 4096 == (total_space_used = 3971 + whats_left = 125)
11:40:45.306 [27487] <2> find_processes: realloc down to 3971
11:40:45.352 [27487] <2> bpcd main: BPCD_DISCONNECT_RQST
11:40:45.352 [27487] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
11:41:46.755 [27513] <2> bpcd main: offset to GMT -28800
11:41:46.756 [27513] <2> logconnections: BPCD ACCEPT FROM 10.87.0.198.59287 TO 10.87.0.198.13724
11:41:46.756 [27513] <2> bpcd main: setup_sockopts complete
11:41:46.765 [27513] <2> bpcd peer_hostname: Connection from host bckserver01 (10.87.0.198) port 59287
11:41:46.774 [27513] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:41:46.774 [27513] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:41:46.801 [27513] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:41:46.801 [27513] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:41:46.802 [27513] <2> bpcd valid_server: comparing bckserver01 and bckserver01
11:41:46.803 [27513] <4> bpcd valid_server: hostname comparison succeeded
11:41:46.803 [27513] <2> bpcd main: output socket port number = 1
11:41:46.807 [27513] <2> bpcd main: Duplicated vnetd socket on stderr
11:41:46.807 [27513] <2> bpcd main: <---- NetBackup 6.0 0 ------------initiated
11:41:46.852 [27513] <2> bpcd main: Not using VxSS authentication with bckserver01
11:41:46.902 [27513] <2> bpcd main: BPCD_GET_PROCESS_STATUS_RQST
11:41:46.952 [27513] <2> find_processes: total malloc-ed = 4096
11:41:46.965 [27513] <2> get_unxwre_processes: 118 files considered, 116 files opened/read
11:41:46.965 [27513] <2> find_processes: progs_list_size = 4096 == total calculated size = 4096 == (total_space_used = 3971 + whats_left = 125)
11:41:46.965 [27513] <2> find_processes: realloc down to 3971
11:41:47.013 [27513] <2> bpcd main: BPCD_DISCONNECT_RQST
11:41:47.013 [27513] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
11:42:48.426 [27537] <2> bpcd main: offset to GMT -28800
11:42:48.426 [27537] <2> logconnections: BPCD ACCEPT FROM 10.87.0.198.59364 TO 10.87.0.198.13724
11:42:48.426 [27537] <2> bpcd main: setup_sockopts complete
11:42:48.435 [27537] <2> bpcd peer_hostname: Connection from host bckserver01 (10.87.0.198) port 59364
11:42:48.444 [27537] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:42:48.444 [27537] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:42:48.472 [27537] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:42:48.473 [27537] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:42:48.474 [27537] <2> bpcd valid_server: comparing bckserver01 and bckserver01
11:42:48.474 [27537] <4> bpcd valid_server: hostname comparison succeeded
11:42:48.475 [27537] <2> bpcd main: output socket port number = 1
11:42:48.476 [27537] <2> bpcd main: Duplicated vnetd socket on stderr
11:42:48.476 [27537] <2> bpcd main: <---- NetBackup 6.0 0 ------------initiated
11:42:48.523 [27537] <2> bpcd main: Not using VxSS authentication with bckserver01
11:42:48.573 [27537] <2> bpcd main: BPCD_GET_PROCESS_STATUS_RQST
11:42:48.623 [27537] <2> find_processes: total malloc-ed = 4096
11:42:48.637 [27537] <2> get_unxwre_processes: 118 files considered, 116 files opened/read
11:42:48.637 [27537] <2> find_processes: progs_list_size = 4096 == total calculated size = 4096 == (total_space_used = 3971 + whats_left = 125)
11:42:48.637 [27537] <2> find_processes: realloc down to 3971
11:42:48.683 [27537] <2> bpcd main: BPCD_DISCONNECT_RQST
11:42:48.683 [27537] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
11:43:50.097 [27560] <2> bpcd main: offset to GMT -28800
11:43:50.097 [27560] <2> logconnections: BPCD ACCEPT FROM 10.87.0.198.59442 TO 10.87.0.198.13724
11:43:50.097 [27560] <2> bpcd main: setup_sockopts complete
11:43:50.106 [27560] <2> bpcd peer_hostname: Connection from host bckserver01 (10.87.0.198) port 59442
11:43:50.114 [27560] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:43:50.115 [27560] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:43:50.142 [27560] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:43:50.142 [27560] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:43:50.144 [27560] <2> bpcd valid_server: comparing bckserver01 and bckserver01
11:43:50.144 [27560] <4> bpcd valid_server: hostname comparison succeeded
11:43:50.145 [27560] <2> bpcd main: output socket port number = 1
11:43:50.146 [27560] <2> bpcd main: Duplicated vnetd socket on stderr
11:43:50.146 [27560] <2> bpcd main: <---- NetBackup 6.0 0 ------------initiated
11:43:50.193 [27560] <2> bpcd main: Not using VxSS authentication with bckserver01
11:43:50.243 [27560] <2> bpcd main: BPCD_GET_PROCESS_STATUS_RQST
11:43:50.293 [27560] <2> find_processes: total malloc-ed = 4096
11:43:50.306 [27560] <2> get_unxwre_processes: 118 files considered, 116 files opened/read
11:43:50.306 [27560] <2> find_processes: progs_list_size = 4096 == total calculated size = 4096 == (total_space_used = 3971 + whats_left = 125)
11:43:50.306 [27560] <2> find_processes: realloc down to 3971
11:43:50.354 [27560] <2> bpcd main: BPCD_DISCONNECT_RQST
11:43:50.354 [27560] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
11:44:51.764 [27583] <2> bpcd main: offset to GMT -28800
11:44:51.764 [27583] <2> logconnections: BPCD ACCEPT FROM 10.87.0.198.59519 TO 10.87.0.198.13724
11:44:51.764 [27583] <2> bpcd main: setup_sockopts complete
11:44:51.773 [27583] <2> bpcd peer_hostname: Connection from host bckserver01 (10.87.0.198) port 59519
11:44:51.782 [27583] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:44:51.782 [27583] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:44:51.811 [27583] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:44:51.811 [27583] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:44:51.812 [27583] <2> bpcd valid_server: comparing bckserver01 and bckserver01
11:44:51.813 [27583] <4> bpcd valid_server: hostname comparison succeeded
11:44:51.813 [27583] <2> bpcd main: output socket port number = 1
11:44:51.815 [27583] <2> bpcd main: Duplicated vnetd socket on stderr
11:44:51.815 [27583] <2> bpcd main: <---- NetBackup 6.0 0 ------------initiated
11:44:51.864 [27583] <2> bpcd main: Not using VxSS authentication with bckserver01
11:44:51.914 [27583] <2> bpcd main: BPCD_GET_PROCESS_STATUS_RQST
11:44:51.964 [27583] <2> find_processes: total malloc-ed = 4096
11:44:51.976 [27583] <2> get_unxwre_processes: 118 files considered, 116 files opened/read
11:44:51.976 [27583] <2> find_processes: progs_list_size = 4096 == total calculated size = 4096 == (total_space_used = 3970 + whats_left = 126)
11:44:51.976 [27583] <2> find_processes: realloc down to 3970
11:44:52.024 [27583] <2> bpcd main: BPCD_DISCONNECT_RQST
11:44:52.024 [27583] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
11:45:53.434 [27607] <2> bpcd main: offset to GMT -28800
11:45:53.434 [27607] <2> logconnections: BPCD ACCEPT FROM 10.87.0.198.59597 TO 10.87.0.198.13724
11:45:53.434 [27607] <2> bpcd main: setup_sockopts complete
11:45:53.443 [27607] <2> bpcd peer_hostname: Connection from host bckserver01 (10.87.0.198) port 59597
11:45:53.451 [27607] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:45:53.451 [27607] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:45:53.477 [27607] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:45:53.477 [27607] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:45:53.478 [27607] <2> bpcd valid_server: comparing bckserver01 and bckserver01
11:45:53.478 [27607] <4> bpcd valid_server: hostname comparison succeeded
11:45:53.479 [27607] <2> bpcd main: output socket port number = 1
11:45:53.480 [27607] <2> bpcd main: Duplicated vnetd socket on stderr
11:45:53.480 [27607] <2> bpcd main: <---- NetBackup 6.0 0 ------------initiated
11:45:53.525 [27607] <2> bpcd main: Not using VxSS authentication with bckserver01
11:45:53.575 [27607] <2> bpcd main: BPCD_GET_PROCESS_STATUS_RQST
11:45:53.624 [27607] <2> find_processes: total malloc-ed = 4096
11:45:53.638 [27607] <2> get_unxwre_processes: 118 files considered, 116 files opened/read
11:45:53.639 [27607] <2> find_processes: progs_list_size = 4096 == total calculated size = 4096 == (total_space_used = 3971 + whats_left = 125)
11:45:53.639 [27607] <2> find_processes: realloc down to 3971
11:45:53.685 [27607] <2> bpcd main: BPCD_DISCONNECT_RQST
11:45:53.685 [27607] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
11:46:09.714 [27626] <2> bpcd main: offset to GMT -28800
11:46:09.714 [27626] <2> logconnections: BPCD ACCEPT FROM 10.87.0.198.59622 TO 10.87.0.198.13724
11:46:09.714 [27626] <2> bpcd main: setup_sockopts complete
11:46:09.723 [27626] <2> bpcd peer_hostname: Connection from host bckserver01 (10.87.0.198) port 59622
11:46:09.732 [27626] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:46:09.732 [27626] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:46:09.760 [27626] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:46:09.760 [27626] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:46:09.761 [27626] <2> bpcd valid_server: comparing bckserver01 and bckserver01
11:46:09.762 [27626] <4> bpcd valid_server: hostname comparison succeeded
11:46:09.762 [27626] <2> bpcd main: output socket port number = 1
11:46:09.766 [27626] <2> bpcd main: Duplicated vnetd socket on stderr
11:46:09.766 [27626] <2> bpcd main: <---- NetBackup 6.0 0 ------------initiated
11:46:09.824 [27626] <2> bpcd main: Not using VxSS authentication with bckserver01
11:46:09.865 [27626] <2> bpcd main: BPCD_FORK_CMD_RQST
11:46:09.865 [27626] <2> bpcd main: fork cmd = /usr/openv/netbackup/bin/bptm bptm -rptdrv -jobid -1303437598 -jm
11:46:09.865 [27626] <2> bpcd main: filter type = -1
11:46:09.865 [27626] <2> bpcd main: backuptime before fork = 0
11:46:09.867 [27629] <2> bpcd main: Converting args string into an argv
11:46:09.867 [27629] <2> bpcd main: Done converting args string into an argv
11:46:09.867 [27629] <2> bpcd main: child_args[0] = /usr/openv/netbackup/bin/bptm
11:46:09.867 [27629] <2> bpcd main: child_args[1] = bptm
11:46:09.867 [27629] <2> bpcd main: child_args[2] = -rptdrv
11:46:09.867 [27629] <2> bpcd main: child_args[3] = -jobid
11:46:09.867 [27629] <2> bpcd main: child_args[4] = -1303437598
11:46:09.867 [27629] <2> bpcd main: child_args[5] = -jm
11:46:09.867 [27629] <2> bpcd main: Before execvp of command
11:46:09.870 [27626] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
11:46:55.098 [27640] <2> bpcd main: offset to GMT -28800
11:46:55.098 [27640] <2> logconnections: BPCD ACCEPT FROM 10.87.0.198.59708 TO 10.87.0.198.13724
11:46:55.099 [27640] <2> bpcd main: setup_sockopts complete
11:46:55.108 [27640] <2> bpcd peer_hostname: Connection from host bckserver01 (10.87.0.198) port 59708
11:46:55.117 [27640] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:46:55.117 [27640] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:46:55.146 [27640] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:46:55.146 [27640] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:46:55.147 [27640] <2> bpcd valid_server: comparing bckserver01 and bckserver01
11:46:55.148 [27640] <4> bpcd valid_server: hostname comparison succeeded
11:46:55.148 [27640] <2> bpcd main: output socket port number = 1
11:46:55.152 [27640] <2> bpcd main: Duplicated vnetd socket on stderr
11:46:55.152 [27640] <2> bpcd main: <---- NetBackup 6.0 0 ------------initiated
11:46:55.195 [27640] <2> bpcd main: Not using VxSS authentication with bckserver01
11:46:55.245 [27640] <2> bpcd main: BPCD_GET_PROCESS_STATUS_RQST
11:46:55.295 [27640] <2> find_processes: total malloc-ed = 4096
11:46:55.310 [27640] <2> get_unxwre_processes: 118 files considered, 116 files opened/read
11:46:55.310 [27640] <2> find_processes: progs_list_size = 4096 == total calculated size = 4096 == (total_space_used = 3970 + whats_left = 126)
11:46:55.310 [27640] <2> find_processes: realloc down to 3970
11:46:55.355 [27640] <2> bpcd main: BPCD_DISCONNECT_RQST
11:46:55.355 [27640] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
11:47:56.765 [27663] <2> bpcd main: offset to GMT -28800
11:47:56.765 [27663] <2> logconnections: BPCD ACCEPT FROM 10.87.0.198.59786 TO 10.87.0.198.13724
11:47:56.766 [27663] <2> bpcd main: setup_sockopts complete
11:47:56.774 [27663] <2> bpcd peer_hostname: Connection from host bckserver01 (10.87.0.198) port 59786
11:47:56.783 [27663] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:47:56.783 [27663] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:47:56.811 [27663] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:47:56.811 [27663] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:47:56.813 [27663] <2> bpcd valid_server: comparing bckserver01 and bckserver01
11:47:56.813 [27663] <4> bpcd valid_server: hostname comparison succeeded
11:47:56.813 [27663] <2> bpcd main: output socket port number = 1
11:47:56.815 [27663] <2> bpcd main: Duplicated vnetd socket on stderr
11:47:56.815 [27663] <2> bpcd main: <---- NetBackup 6.0 0 ------------initiated
11:47:56.856 [27663] <2> bpcd main: Not using VxSS authentication with bckserver01
11:47:56.906 [27663] <2> bpcd main: BPCD_GET_PROCESS_STATUS_RQST
11:47:56.956 [27663] <2> find_processes: total malloc-ed = 4096
11:47:56.969 [27663] <2> get_unxwre_processes: 118 files considered, 116 files opened/read
11:47:56.970 [27663] <2> find_processes: progs_list_size = 4096 == total calculated size = 4096 == (total_space_used = 3970 + whats_left = 126)
11:47:56.970 [27663] <2> find_processes: realloc down to 3970
11:47:57.016 [27663] <2> bpcd main: BPCD_DISCONNECT_RQST
11:47:57.016 [27663] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
11:48:58.425 [27687] <2> bpcd main: offset to GMT -28800
11:48:58.425 [27687] <2> logconnections: BPCD ACCEPT FROM 10.87.0.198.59863 TO 10.87.0.198.13724
11:48:58.425 [27687] <2> bpcd main: setup_sockopts complete
11:48:58.434 [27687] <2> bpcd peer_hostname: Connection from host bckserver01 (10.87.0.198) port 59863
11:48:58.443 [27687] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:48:58.444 [27687] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:48:58.473 [27687] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:48:58.473 [27687] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:48:58.474 [27687] <2> bpcd valid_server: comparing bckserver01 and bckserver01
11:48:58.475 [27687] <4> bpcd valid_server: hostname comparison succeeded
11:48:58.475 [27687] <2> bpcd main: output socket port number = 1
11:48:58.479 [27687] <2> bpcd main: Duplicated vnetd socket on stderr
11:48:58.479 [27687] <2> bpcd main: <---- NetBackup 6.0 0 ------------initiated
11:48:58.516 [27687] <2> bpcd main: Not using VxSS authentication with bckserver01
11:48:58.566 [27687] <2> bpcd main: BPCD_GET_PROCESS_STATUS_RQST
11:48:58.616 [27687] <2> find_processes: total malloc-ed = 4096
11:48:58.629 [27687] <2> get_unxwre_processes: 118 files considered, 116 files opened/read
11:48:58.629 [27687] <2> find_processes: progs_list_size = 4096 == total calculated size = 4096 == (total_space_used = 3970 + whats_left = 126)
11:48:58.629 [27687] <2> find_processes: realloc down to 3970
11:48:58.676 [27687] <2> bpcd main: BPCD_DISCONNECT_RQST
11:48:58.677 [27687] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
11:49:40.286 [27707] <2> bpcd main: offset to GMT -28800
11:49:40.286 [27707] <2> logconnections: BPCD ACCEPT FROM 10.87.0.198.59916 TO 10.87.0.198.13724
11:49:40.287 [27707] <2> bpcd main: setup_sockopts complete
11:49:40.296 [27707] <2> bpcd peer_hostname: Connection from host bckserver01 (10.87.0.198) port 59916
11:49:40.304 [27707] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:49:40.304 [27707] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:49:40.333 [27707] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:49:40.333 [27707] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:49:40.334 [27707] <2> bpcd valid_server: comparing bckserver01 and bckserver01
11:49:40.335 [27707] <4> bpcd valid_server: hostname comparison succeeded
11:49:40.335 [27707] <2> bpcd main: output socket port number = 1
11:49:40.337 [27707] <2> bpcd main: Duplicated vnetd socket on stderr
11:49:40.337 [27707] <2> bpcd main: <---- NetBackup 6.0 0 ------------initiated
11:49:40.377 [27707] <2> bpcd main: Not using VxSS authentication with bckserver01
11:49:40.427 [27707] <2> bpcd main: BPCD_IMMED_CMD_RQST
11:49:40.427 [27707] <2> bpcd main: immed cmd = /usr/openv/netbackup/bin/bptm -delete_expired 1>/dev/null 2>/dev/null
11:49:40.427 [27707] <2> sanitary_mb_str: detected /dev/null after redirection
11:49:40.427 [27707] <2> sanitary_mb_str: detected /dev/null after redirection
11:49:40.427 [27707] <2> sanitary_mb_str: String "/usr/openv/netbackup/bin/bptm -delete_expired 1>/dev/null 2>/dev/null" is considered sanitary.
11:49:40.684 [27707] <2> bpcd main: completed command
11:49:40.684 [27707] <2> bpcd main: immed cmd status = 0x0
11:49:40.684 [27707] <2> bpcd main: immed cmd status = 0
11:49:40.684 [27707] <2> bpcd main: BPCD_DISCONNECT_RQST
11:49:40.684 [27707] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
11:49:40.786 [27711] <2> bpcd main: offset to GMT -28800
11:49:40.786 [27711] <2> logconnections: BPCD ACCEPT FROM 10.87.0.198.59924 TO 10.87.0.198.13724
11:49:40.786 [27711] <2> bpcd main: setup_sockopts complete
11:49:40.795 [27711] <2> bpcd peer_hostname: Connection from host bckserver01 (10.87.0.198) port 59924
11:49:40.803 [27711] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:49:40.803 [27711] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:49:40.829 [27711] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:49:40.829 [27711] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:49:40.830 [27711] <2> bpcd valid_server: comparing bckserver01 and bckserver01
11:49:40.830 [27711] <4> bpcd valid_server: hostname comparison succeeded
11:49:40.830 [27711] <2> bpcd main: output socket port number = 1
11:49:40.832 [27711] <2> bpcd main: Duplicated vnetd socket on stderr
11:49:40.832 [27711] <2> bpcd main: <---- NetBackup 6.0 0 ------------initiated
11:49:40.877 [27711] <2> bpcd main: Not using VxSS authentication with bckserver01
11:49:40.927 [27711] <2> bpcd main: BPCD_IMMED_CMD_RQST
11:49:40.927 [27711] <2> bpcd main: immed cmd = /usr/openv/netbackup/bin/bptm -delete_all_expired 1>/dev/null 2>/dev/null
11:49:40.927 [27711] <2> sanitary_mb_str: detected /dev/null after redirection
11:49:40.927 [27711] <2> sanitary_mb_str: detected /dev/null after redirection
11:49:40.927 [27711] <2> sanitary_mb_str: String "/usr/openv/netbackup/bin/bptm -delete_all_expired 1>/dev/null 2>/dev/null" is considered sanitary.
11:49:41.390 [27711] <2> bpcd main: completed command
11:49:41.390 [27711] <2> bpcd main: immed cmd status = 0x0
11:49:41.390 [27711] <2> bpcd main: immed cmd status = 0
11:49:41.390 [27711] <2> bpcd main: BPCD_DISCONNECT_RQST
11:49:41.390 [27711] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
11:50:00.082 [27718] <2> bpcd main: offset to GMT -28800
11:50:00.082 [27718] <2> logconnections: BPCD ACCEPT FROM 10.87.0.198.59958 TO 10.87.0.198.13724
11:50:00.082 [27718] <2> bpcd main: setup_sockopts complete
11:50:00.091 [27718] <2> bpcd peer_hostname: Connection from host bckserver01 (10.87.0.198) port 59958
11:50:00.100 [27718] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:50:00.100 [27718] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:50:00.128 [27718] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:50:00.128 [27718] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:50:00.129 [27718] <2> bpcd valid_server: comparing bckserver01 and bckserver01
11:50:00.130 [27718] <4> bpcd valid_server: hostname comparison succeeded
11:50:00.130 [27718] <2> bpcd main: output socket port number = 1
11:50:00.134 [27718] <2> bpcd main: Duplicated vnetd socket on stderr
11:50:00.134 [27718] <2> bpcd main: <---- NetBackup 6.0 0 ------------initiated
11:50:00.177 [27718] <2> bpcd main: Not using VxSS authentication with bckserver01
11:50:00.227 [27718] <2> bpcd main: BPCD_GET_PROCESS_STATUS_RQST
11:50:00.277 [27718] <2> find_processes: total malloc-ed = 4096
11:50:00.291 [27718] <2> get_unxwre_processes: 118 files considered, 116 files opened/read
11:50:00.291 [27718] <2> find_processes: progs_list_size = 4096 == total calculated size = 4096 == (total_space_used = 3970 + whats_left = 126)
11:50:00.291 [27718] <2> find_processes: realloc down to 3970
11:50:00.337 [27718] <2> bpcd main: BPCD_DISCONNECT_RQST
11:50:00.337 [27718] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
11:51:01.758 [27756] <2> bpcd main: offset to GMT -28800
11:51:01.759 [27756] <2> logconnections: BPCD ACCEPT FROM 10.87.0.198.60035 TO 10.87.0.198.13724
11:51:01.759 [27756] <2> bpcd main: setup_sockopts complete
11:51:01.768 [27756] <2> bpcd peer_hostname: Connection from host bckserver01 (10.87.0.198) port 60035
11:51:01.777 [27756] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:51:01.777 [27756] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:51:01.805 [27756] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:51:01.805 [27756] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:51:01.806 [27756] <2> bpcd valid_server: comparing bckserver01 and bckserver01
11:51:01.806 [27756] <4> bpcd valid_server: hostname comparison succeeded
11:51:01.807 [27756] <2> bpcd main: output socket port number = 1
11:51:01.809 [27756] <2> bpcd main: Duplicated vnetd socket on stderr
11:51:01.809 [27756] <2> bpcd main: <---- NetBackup 6.0 0 ------------initiated
11:51:01.849 [27756] <2> bpcd main: Not using VxSS authentication with bckserver01
11:51:01.899 [27756] <2> bpcd main: BPCD_GET_PROCESS_STATUS_RQST
11:51:01.949 [27756] <2> find_processes: total malloc-ed = 4096
11:51:01.961 [27756] <2> get_unxwre_processes: 118 files considered, 116 files opened/read
11:51:01.961 [27756] <2> find_processes: progs_list_size = 4096 == total calculated size = 4096 == (total_space_used = 3970 + whats_left = 126)
11:51:01.961 [27756] <2> find_processes: realloc down to 3970
11:51:02.011 [27756] <2> bpcd main: BPCD_DISCONNECT_RQST
11:51:02.011 [27756] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
11:52:03.433 [27785] <2> bpcd main: offset to GMT -28800
11:52:03.433 [27785] <2> logconnections: BPCD ACCEPT FROM 10.87.0.198.60120 TO 10.87.0.198.13724
11:52:03.433 [27785] <2> bpcd main: setup_sockopts complete
11:52:03.443 [27785] <2> bpcd peer_hostname: Connection from host bckserver01 (10.87.0.198) port 60120
11:52:03.452 [27785] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:52:03.452 [27785] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:52:03.482 [27785] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:52:03.482 [27785] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:52:03.483 [27785] <2> bpcd valid_server: comparing bckserver01 and bckserver01
11:52:03.483 [27785] <4> bpcd valid_server: hostname comparison succeeded
11:52:03.484 [27785] <2> bpcd main: output socket port number = 1
11:52:03.488 [27785] <2> bpcd main: Duplicated vnetd socket on stderr
11:52:03.488 [27785] <2> bpcd main: <---- NetBackup 6.0 0 ------------initiated
11:52:03.529 [27785] <2> bpcd main: Not using VxSS authentication with bckserver01
11:52:03.579 [27785] <2> bpcd main: BPCD_GET_PROCESS_STATUS_RQST
11:52:03.629 [27785] <2> find_processes: total malloc-ed = 4096
11:52:03.644 [27785] <2> get_unxwre_processes: 118 files considered, 116 files opened/read
11:52:03.644 [27785] <2> find_processes: progs_list_size = 4096 == total calculated size = 4096 == (total_space_used = 3970 + whats_left = 126)
11:52:03.644 [27785] <2> find_processes: realloc down to 3970
11:52:03.689 [27785] <2> bpcd main: BPCD_DISCONNECT_RQST
11:52:03.690 [27785] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
11:53:05.110 [27808] <2> bpcd main: offset to GMT -28800
11:53:05.111 [27808] <2> logconnections: BPCD ACCEPT FROM 10.87.0.198.60197 TO 10.87.0.198.13724
11:53:05.111 [27808] <2> bpcd main: setup_sockopts complete
11:53:05.119 [27808] <2> bpcd peer_hostname: Connection from host bckserver01 (10.87.0.198) port 60197
11:53:05.129 [27808] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:53:05.129 [27808] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:53:05.154 [27808] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:53:05.155 [27808] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:53:05.156 [27808] <2> bpcd valid_server: comparing bckserver01 and bckserver01
11:53:05.156 [27808] <4> bpcd valid_server: hostname comparison succeeded
11:53:05.156 [27808] <2> bpcd main: output socket port number = 1
11:53:05.158 [27808] <2> bpcd main: Duplicated vnetd socket on stderr
11:53:05.158 [27808] <2> bpcd main: <---- NetBackup 6.0 0 ------------initiated
11:53:05.200 [27808] <2> bpcd main: Not using VxSS authentication with bckserver01
11:53:05.250 [27808] <2> bpcd main: BPCD_GET_PROCESS_STATUS_RQST
11:53:05.300 [27808] <2> find_processes: total malloc-ed = 4096
11:53:05.314 [27808] <2> get_unxwre_processes: 118 files considered, 116 files opened/read
11:53:05.314 [27808] <2> find_processes: progs_list_size = 4096 == total calculated size = 4096 == (total_space_used = 3968 + whats_left = 128)
11:53:05.314 [27808] <2> find_processes: realloc down to 3968
11:53:05.360 [27808] <2> bpcd main: BPCD_DISCONNECT_RQST
11:53:05.360 [27808] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
11:54:06.770 [27832] <2> bpcd main: offset to GMT -28800
11:54:06.770 [27832] <2> logconnections: BPCD ACCEPT FROM 10.87.0.198.60275 TO 10.87.0.198.13724
11:54:06.771 [27832] <2> bpcd main: setup_sockopts complete
11:54:06.779 [27832] <2> bpcd peer_hostname: Connection from host bckserver01 (10.87.0.198) port 60275
11:54:06.788 [27832] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:54:06.789 [27832] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:54:06.814 [27832] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:54:06.814 [27832] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:54:06.816 [27832] <2> bpcd valid_server: comparing bckserver01 and bckserver01
11:54:06.816 [27832] <4> bpcd valid_server: hostname comparison succeeded
11:54:06.816 [27832] <2> bpcd main: output socket port number = 1
11:54:06.818 [27832] <2> bpcd main: Duplicated vnetd socket on stderr
11:54:06.818 [27832] <2> bpcd main: <---- NetBackup 6.0 0 ------------initiated
11:54:06.861 [27832] <2> bpcd main: Not using VxSS authentication with bckserver01
11:54:06.910 [27832] <2> bpcd main: BPCD_GET_PROCESS_STATUS_RQST
11:54:06.960 [27832] <2> find_processes: total malloc-ed = 4096
11:54:06.975 [27832] <2> get_unxwre_processes: 118 files considered, 116 files opened/read
11:54:06.975 [27832] <2> find_processes: progs_list_size = 4096 == total calculated size = 4096 == (total_space_used = 3979 + whats_left = 117)
11:54:06.975 [27832] <2> find_processes: realloc down to 3979
11:54:07.021 [27832] <2> bpcd main: BPCD_DISCONNECT_RQST
11:54:07.021 [27832] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
11:55:08.430 [27855] <2> bpcd main: offset to GMT -28800
11:55:08.431 [27855] <2> logconnections: BPCD ACCEPT FROM 10.87.0.198.60352 TO 10.87.0.198.13724
11:55:08.431 [27855] <2> bpcd main: setup_sockopts complete
11:55:08.440 [27855] <2> bpcd peer_hostname: Connection from host bckserver01 (10.87.0.198) port 60352
11:55:08.448 [27855] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:55:08.448 [27855] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:55:08.474 [27855] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:55:08.474 [27855] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:55:08.475 [27855] <2> bpcd valid_server: comparing bckserver01 and bckserver01
11:55:08.476 [27855] <4> bpcd valid_server: hostname comparison succeeded
11:55:08.476 [27855] <2> bpcd main: output socket port number = 1
11:55:08.480 [27855] <2> bpcd main: Duplicated vnetd socket on stderr
11:55:08.480 [27855] <2> bpcd main: <---- NetBackup 6.0 0 ------------initiated
11:55:08.521 [27855] <2> bpcd main: Not using VxSS authentication with bckserver01
11:55:08.571 [27855] <2> bpcd main: BPCD_GET_PROCESS_STATUS_RQST
11:55:08.621 [27855] <2> find_processes: total malloc-ed = 4096
11:55:08.634 [27855] <2> get_unxwre_processes: 118 files considered, 116 files opened/read
11:55:08.634 [27855] <2> find_processes: progs_list_size = 4096 == total calculated size = 4096 == (total_space_used = 3972 + whats_left = 124)
11:55:08.634 [27855] <2> find_processes: realloc down to 3972
11:55:08.681 [27855] <2> bpcd main: BPCD_DISCONNECT_RQST
11:55:08.681 [27855] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
11:56:10.095 [27882] <2> bpcd main: offset to GMT -28800
11:56:10.095 [27882] <2> logconnections: BPCD ACCEPT FROM 10.87.0.198.60432 TO 10.87.0.198.13724
11:56:10.095 [27882] <2> bpcd main: setup_sockopts complete
11:56:10.105 [27881] <2> bpcd main: offset to GMT -28800
11:56:10.105 [27881] <2> logconnections: BPCD ACCEPT FROM 10.87.0.198.60430 TO 10.87.0.198.13724
11:56:10.105 [27882] <2> bpcd peer_hostname: Connection from host bckserver01 (10.87.0.198) port 60432
11:56:10.105 [27881] <2> bpcd main: setup_sockopts complete
11:56:10.114 [27882] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:56:10.114 [27882] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:56:10.115 [27881] <2> bpcd peer_hostname: Connection from host bckserver01 (10.87.0.198) port 60430
11:56:10.124 [27881] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:56:10.124 [27881] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:56:10.141 [27882] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:56:10.141 [27882] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:56:10.143 [27882] <2> bpcd valid_server: comparing bckserver01 and bckserver01
11:56:10.143 [27882] <4> bpcd valid_server: hostname comparison succeeded
11:56:10.143 [27882] <2> bpcd main: output socket port number = 1
11:56:10.163 [27881] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:56:10.163 [27881] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:56:10.164 [27881] <2> bpcd valid_server: comparing bckserver01 and bckserver01
11:56:10.165 [27881] <4> bpcd valid_server: hostname comparison succeeded
11:56:10.165 [27881] <2> bpcd main: output socket port number = 1
11:56:10.233 [27882] <2> bpcd main: Duplicated vnetd socket on stderr
11:56:10.233 [27882] <2> bpcd main: <---- NetBackup 6.0 0 ------------initiated
11:56:10.269 [27881] <2> bpcd main: Duplicated vnetd socket on stderr
11:56:10.269 [27881] <2> bpcd main: <---- NetBackup 6.0 0 ------------initiated
11:56:10.311 [27882] <2> bpcd main: Not using VxSS authentication with bckserver01
11:56:10.312 [27881] <2> bpcd main: Not using VxSS authentication with bckserver01
11:56:10.352 [27882] <2> bpcd main: BPCD_FORK_CMD_RQST
11:56:10.352 [27882] <2> bpcd main: fork cmd = /usr/openv/netbackup/bin/bptm bptm -rptdrv -jobid -1303437601 -jm
11:56:10.352 [27882] <2> bpcd main: filter type = -1
11:56:10.352 [27882] <2> bpcd main: backuptime before fork = 0
11:56:10.354 [27886] <2> bpcd main: Converting args string into an argv
11:56:10.354 [27886] <2> bpcd main: Done converting args string into an argv
11:56:10.354 [27886] <2> bpcd main: child_args[0] = /usr/openv/netbackup/bin/bptm
11:56:10.354 [27886] <2> bpcd main: child_args[1] = bptm
11:56:10.354 [27886] <2> bpcd main: child_args[2] = -rptdrv
11:56:10.354 [27886] <2> bpcd main: child_args[3] = -jobid
11:56:10.354 [27886] <2> bpcd main: child_args[4] = -1303437601
11:56:10.354 [27886] <2> bpcd main: child_args[5] = -jm
11:56:10.354 [27886] <2> bpcd main: Before execvp of command
11:56:10.362 [27881] <2> bpcd main: BPCD_GET_PROCESS_STATUS_RQST
11:56:10.363 [27882] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
 

 

 

kylix
Level 4

Today, at last I get log informations of low backup speed,

bpbkar log on client:

11:37:19.005 AM: [7084.256] <4> ov_log::OVInit: INF - Starting log file: C:\VERITAS1\NetBackup\logs\BPBKAR\050311.LOG

11:37:19.005 AM: [7084.256] <4> ov_log::OVInit: GENERAL Log Level: 0
11:37:19.005 AM: [7084.256] <4> ov_log::OVInit: TCP Log Level: 0
11:37:19.005 AM: [7084.256] <4> ov_log::OVInit: INF - the log mutex: 1964
BPBKAR  NetBackup Backup/Archive  6.0GA  [Nov  7 2006]
Copyright 1993 - 2005 VERITAS Software Corporation
All Rights Reserved.

11:37:19.005 AM: [7084.256] <2> WinMain: DAT - _pgmptr = 'C:\VERITAS1\NetBackup\bin\bpbkar32.exe'
11:37:19.005 AM: [7084.256] <2> WinMain: DAT - lpCmdLine = '-r 1209600 -ru root -dt 0 -to 0 -clnt 10.67.99.227 -class File_10.67.99.227 -sched Full -st FULL -bpstart_to 300 -bpend_to 300 -read_to 3000 -use_otm -fso -b 10.67.99.227_1304393831 -WOFB_enabled -WOFB_fim 0 -WOFB_usage 0 -WOFB_error 0 -ct 13 -use_ofb '
11:37:19.005 AM: [7084.256] <2> date_debug: DAT - timezone: China Standard Time, offset=-28800, dst: NONE
11:37:19.005 AM: [7084.256] <2> date_debug: DAT - current time: 1304393839, 5/3/2011 11:37:19 AM
11:37:19.005 AM: [7084.256] <2> date_debug: DAT - 01/01/94 UCT:  757382400, 1/1/1994 8:00:00 AM
11:37:19.005 AM: [7084.256] <2> date_debug: DAT - 07/01/94 UCT:  773020800, 7/1/1994 8:00:00 AM
11:37:19.005 AM: [7084.256] <2> WinMain: DAT - standard input handle = 376
11:37:19.005 AM: [7084.256] <2> WinMain: DAT - standard output handle = 1960
11:37:19.005 AM: [7084.256] <2> WinMain: DAT - standard error handle = 1904
11:37:19.020 AM: [7084.256] <4> tar_backup::V_SetupJobData: INF - dwJobData: ffffffff
11:37:19.020 AM: [7084.256] <4> tar_backup::V_SetupJobData: INF -     dwJob: ffffffff
11:37:19.036 AM: [7084.256] <4> dos_backup::V_PreProcessing: INF - backup privileges enabled, previous = 0
11:37:19.036 AM: [7084.256] <4> dos_backup::V_PreProcessing: INF - restore privileges enabled, previous = 0
11:37:19.036 AM: [7084.256] <4> dos_backup::V_PreProcessing: INF - security privileges enabled, previous = 0
11:37:19.036 AM: [7084.256] <4> dos_backup::V_PreProcessing: INF - tcb privileges enabled, previous = 0
11:37:19.036 AM: [7084.256] <4> dos_backup::V_PreProcessing: INF - create token privileges enabled, previous = 0
11:37:19.036 AM: [7084.256] <4> dos_backup::V_PreProcessing: INF - user name: root
11:37:19.036 AM: [7084.256] <4> dos_backup::V_PreProcessing: INF - no access token: scheduled backup
11:37:19.286 AM: [7084.256] <2> tar_backup::V_SetupProcessContinue: TAR - CONTINUE BACKUP received
11:37:19.286 AM: [7084.256] <2> tar_backup::V_SetupFileDirectives: TAR - backup filename = D:\baksql
11:37:19.286 AM: [7084.256] <2> tar_backup::V_SetupFileDirectives: TAR - backup filename = D:\baksql
11:37:19.286 AM: [7084.4972] <4> tar_base::V_KeepaliveThread: INF - The Keepalive thread is active. Keepalive interval 60 Seconds
11:37:19.317 AM: [7084.256] <2> ov_log::V_GlobalLog: INF - BEDS_Init() Enter InitFlags:0x0
11:38:05.100 AM: [7084.256] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: C:
11:38:05.100 AM: [7084.256] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: D:
11:38:05.100 AM: [7084.256] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: E:
11:38:05.100 AM: [7084.256] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Microsoft Terminal Services
11:38:05.100 AM: [7084.256] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Microsoft Windows Network
11:38:05.100 AM: [7084.256] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Web Client Network
11:38:05.100 AM: [7084.256] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Oracle
11:38:05.100 AM: [7084.256] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Shadow?Copy?Components
11:38:07.194 AM: [7084.256] <4> backup_create: INF - NetBackup Temp Directory: 'C:\VERITAS1\\NetBackup\Temp'
11:38:07.194 AM: [7084.256] <2> tar_base::V_vTarMsgW: INF - Enabling volume snapshots for (D:\baksql), please wait...
11:38:07.194 AM: [7084.256] <4> V_DetermineMountInfo: INF - Checking Volume \\?\Volume{0cf4f728-4fd5-11dc-9700-001b789662ec}\ for '\baksql'
11:38:07.194 AM: [7084.256] <4> V_DetermineMountInfo: INF -   Adding NTFS Volume D:\ ==> \\?\Volume{0cf4f728-4fd5-11dc-9700-001b789662ec}\
11:38:07.194 AM: [7084.256] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - ======================================================================
11:38:07.194 AM: [7084.256] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - Attempting to create snapshots for 'D:\baksql'
11:38:07.194 AM: [7084.256] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - CREATE request: C:\VERITAS1\NetBackup\bin\bpfis create -owner NBU -fso -WOFB -fim VSP -id 10.67.99.227_1304393831 "D:\baksql"
11:38:14.210 AM: [7084.256] <4> V_Snapshot::V_Snapshot_ParseBpfisOutput: INF - Snapshot creation, FIS_ID: 10.67.99.227_1304393831
11:38:14.210 AM: [7084.256] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - Snapshot creation was not successful
11:38:14.210 AM: [7084.256] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - ======================================================================
11:38:14.210 AM: [7084.256] <2> tar_base::V_vTarMsgW: INF - Volume snapshots not enabled
12:38:07.895 PM: [7084.256] <4> ov_log::OVLoop: Timestamp
 

 

bptm log on media server:

11:37:19.532 [27396] <2> bptm: INITIATING (VERBOSE = 0): -w -c 10.67.99.227 -den 14 -rt 8 -rn 0 -stunit bckserver01-hcart2-robot-tld-0 -cl File_10.67.99.227 -bt 1304393831 -b 10.67.99.227_1304393831 -st 0 -cj 2 -p NetBackup -reqid -1303437597 -jm -brm -hostname 10.67.99.227 -ru root -rclnt 10.67.99.227 -rclnthostname 10.67.99.227 -rl 1 -rp 1209600 -sl Full -ct 13 -maxfrag 1048576 -mediasvr bckserver01 -no_callback -connect_options 0x01010100 -jobid 147385 -jobgrpid 147385 -masterversion 600000
11:37:19.541 [27396] <2> bptm: EMMserver_name = bckserver01
11:37:19.541 [27396] <2> bptm: EMMserver_port = 1556
11:37:19.550 [27396] <4> db_getSTUNIT: emmserver_name = bckserver01
11:37:19.550 [27396] <4> db_getSTUNIT: emmserver_port = 1556
11:37:19.617 [27396] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:37:19.617 [27396] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:37:19.626 [27396] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name bckserver01
11:37:19.696 [27396] <2> io_init: using 6553600 data buffer size
11:37:19.696 [27396] <2> io_init: CINDEX 0, sched Kbytes for monitoring = 40000
11:37:19.696 [27396] <2> io_set_recvbuf: setting receive network buffer to 65536 bytes
11:37:19.696 [27396] <2> io_init: using 16 data buffers
11:37:19.696 [27396] <2> io_init: child delay = 20, parent delay = 30 (milliseconds)
11:37:19.697 [27396] <2> create_shared_memory: shm_size = 104857988, buffer address = 0xf6400000, buf control = 0xfc800000, ready ptr = 0xfc800180
11:37:19.709 [27396] <2> LOCAL CLASS_ATT_DEFS: Product ID = 6
11:37:19.720 [27396] <2> get_behavior_rec: invalid entry: POSTPROCESS_IMAGES
11:37:19.748 [27396] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2034: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006
11:37:19.748 [27396] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2048: service: bpdbm
11:37:19.793 [27396] <2> logconnections: BPDBM CONNECT FROM 10.87.0.198.58908 TO 10.87.0.198.13724
11:37:19.795 [27396] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:37:19.795 [27396] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:37:19.796 [27396] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:37:19.796 [27396] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:37:19.992 [27396] <2> nbjm_media_request: Passing job control to NBJM, type WRITE
11:37:19.994 [27396] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:37:19.994 [27396] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:37:19.995 [27396] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name bckserver01
11:37:22.222 [27396] <2> RequestMultipleResources: returning
11:37:22.222 [27396] <2> parse_resource_strings: MEDIADB 1 89554 0019L3 4000038 ------ 14 1304387108 1304387230 1305596830 0 21606669 2 2 1 1 0 0 1024 0 3384 0
11:37:22.222 [27396] <2> parse_resource_strings: Parsed message type 15, version 1, 21 parameters
11:37:22.222 [27396] <2> parse_resource_strings: VOLUME 1 0019L3 4000038 000019L3 NetBackup IMATION 0C31582260 14 8 0 2 0 {00000000-0000-0000-0000-000000000000} 0
11:37:22.222 [27396] <2> parse_resource_strings: Parsed message type 16, version 1, 14 parameters
11:37:22.222 [27396] <2> parse_resource_strings: DRIVE 2 IBM.ULTRIUM-TD3.000 2000006 F09903E000 /dev/rmt/0cbn -1 -1 -1 -1 0 0 0 0 *NULL* *NULL* *NULL* *NULL* 1 0
11:37:22.222 [27396] <2> parse_resource_strings: Parsed message type 17, version 2, 19 parameters
11:37:22.222 [27396] <2> parse_resource_strings: STORAGE 0 bckserver01-hcart2-robot-tld-0 14 1048576
11:37:22.222 [27396] <2> parse_resource_strings: Parsed message type 18, version 0, 4 parameters
11:37:22.222 [27396] <2> nbjm_media_request: Job control returned to BPTM
11:37:22.222 [27396] <2> drivename_open: Called with Create 1, file IBM.ULTRIUM-TD3.000
11:37:22.223 [27396] <2> drivename_lock: lock established
11:37:22.223 [27396] <2> drivename_write: Called with mode 0
11:37:22.226 [27396] <2> write_backup: backup child process is pid 27399
11:37:22.226 [27396] <2> mount_open_media: Waiting for mount of media id 0019L3 (copy 1) on server bckserver01.
11:37:22.228 [27396] <4> create_tpreq_file: symlink to path /dev/rmt/0cbn
11:37:22.234 [27396] <2> manage_scsi_reserve: SCSI RESERVE
11:37:22.237 [27396] <4> expandpath: /usr/openv/netbackup/db/media/tpreq/drive_IBM.ULTRIUM-TD3.000
11:38:02.416 [27396] <2> io_open: SCSI RESERVE
11:38:02.439 [27396] <2> io_open: report_attr, fl1 0x00000049, fl2 0x00000004
11:38:02.439 [27396] <2> io_open: Matching medium manufacturer [IMATION], sn [0C31582260]
11:38:02.466 [27396] <2> io_open: file /usr/openv/netbackup/db/media/tpreq/drive_IBM.ULTRIUM-TD3.000 successfully opened (mode 2)
11:38:02.466 [27396] <2> write_backup: media id 0019L3 mounted on drive index 2, drivepath /dev/rmt/0cbn, drivename IBM.ULTRIUM-TD3.000, copy 1
11:38:02.466 [27396] <2> io_read_media_header: drive index 2, reading media header, buflen = 65536, buff = 0x399318, copy 1
11:38:02.467 [27396] <2> io_ioctl: command (5)MTREW 1 from (bptm.c.7675) on drive index 2
11:38:02.496 [27396] <2> io_ioctl: command (1)MTFSF 1 from (bptm.c.7928) on drive index 2
11:38:02.505 [27396] <2> io_position_for_write: position media id 0019L3, copy 1, current number images = 2
11:38:02.505 [27396] <2> io_position_for_write: locating to absolute block number 3384, copy 1
11:38:58.911 [27396] <2> io_position_for_write: locate block is done
11:39:00.434 [27396] <2> io_position_for_write: processing empty header, filenum = 3, bid = (empty_file), copy 1
11:39:00.434 [27396] <2> io_position_for_write: empty header found on 0019L3, OK, copy 1
11:39:00.434 [27396] <2> io_ioctl: command (2)MTBSF 1 from (bptm.c.20241) on drive index 2
11:39:00.446 [27396] <2> io_ioctl: command (0)MTWEOF 1 from (bptm.c.20275) on drive index 2
11:39:05.416 [27396] <2> send_MDS_msg: MEDIADB 1 89554 0019L3 4000038 *NULL* 14 1304387108 1304393831 1305603431 0 21606669 2 2 1 1 0 0 1024 0 3384 0
11:39:05.419 [27396] <2> JobInst::sendIrmMsg: returning
11:39:05.420 [27396] <2> db_error_add_to_file: dberrorq.c:midnite = 1304352000
11:39:05.432 [27396] <4> write_backup: begin writing backup id 10.67.99.227_1304393831, copy 1, fragment 1, to media id 0019L3 on drive index 2
11:39:05.432 [27396] <2> signal_parent: sending SIGUSR1 to bpbrm (pid = 27395)
11:39:05.433 [27396] <2> write_data: ndmp_dup_max_frag is set to 1073741824000
11:39:05.433 [27396] <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:39:05.438 [27396] <2> write_data: absolute block position prior to writing backup header(s) is 3384, copy 1
11:39:05.438 [27396] <2> io_write_back_header: drive index 2, 10.67.99.227_1304393831, file num = 3, mpx_headers = 0, copy 1
11:39:05.445 [27396] <2> write_data: completed writing backup header, start writing data when first buffer is available, copy 1
11:39:39.255 [27456] <2> bptm: INITIATING (VERBOSE = 0): -delete_expired
11:39:39.264 [27456] <2> bptm: EXITING with status 0 <----------
11:39:39.750 [27460] <2> bptm: INITIATING (VERBOSE = 0): -delete_all_expired
11:39:39.760 [27460] <2> bptm: EMMserver_name = bckserver01
11:39:39.760 [27460] <2> bptm: EMMserver_port = 1556
11:39:39.821 [27460] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:39:39.821 [27460] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:39:39.831 [27460] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name bckserver01
11:39:39.938 [27460] <2> bptm: EXITING with status 0 <----------
11:46:10.118 [27629] <2> bptm: INITIATING (VERBOSE = 0): -rptdrv -jobid -1303437598 -jm
11:46:10.127 [27629] <2> bptm: EMMserver_name = bckserver01
11:46:10.127 [27629] <2> bptm: EMMserver_port = 1556
11:46:10.128 [27629] <2> drivename_open: Called with Create 0, file IBM.ULTRIUM-TD3.000
11:46:10.128 [27629] <2> drivename_checklock: Called
11:46:10.128 [27629] <2> drivename_checklock: PID 27396 has lock
11:46:10.128 [27629] <2> report_drives: MODE = 0
11:46:10.128 [27629] <2> report_drives: TIME = 1304393842
11:46:10.128 [27629] <2> report_drives: MASTER = bckserver01
11:46:10.128 [27629] <2> report_drives: PATH = /dev/rmt/0cbn
11:46:10.128 [27629] <2> report_drives: MEDIA = 0019L3
11:46:10.128 [27629] <2> report_drives: REQID = -1303437597
11:46:10.128 [27629] <2> report_drives: ALOCID = 89554
11:46:10.128 [27629] <2> report_drives: RBID = {A1298978-1DD1-11B2-8BF1-00144F8FEBCA}
11:46:10.128 [27629] <2> report_drives: PID = 27396
11:46:10.128 [27629] <2> report_drives: FILE = /usr/openv/netbackup/db/media/tpreq/drive_IBM.ULTRIUM-TD3.000
11:46:10.129 [27629] <2> main: Sending [EXIT STATUS 0] to NBJM
11:46:10.129 [27629] <2> bptm: EXITING with status 0 <----------
11:49:40.665 [27710] <2> bptm: INITIATING (VERBOSE = 0): -delete_expired
11:49:40.674 [27710] <2> bptm: EXITING with status 0 <----------
11:49:41.180 [27714] <2> bptm: INITIATING (VERBOSE = 0): -delete_all_expired
11:49:41.190 [27714] <2> bptm: EMMserver_name = bckserver01
11:49:41.190 [27714] <2> bptm: EMMserver_port = 1556
11:49:41.259 [27714] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:49:41.259 [27714] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:49:41.269 [27714] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name bckserver01
11:49:41.374 [27714] <2> bptm: EXITING with status 0 <----------
11:53:16.080 [27396] <2> write_data: first write, twin_index: 0 cindex: 0 dont_process: 1 wrote_backup_hdr: 1 finished_buff: 0
11:53:16.080 [27396] <2> write_data: received first buffer (6553600 bytes), begin writing data
11:56:10.608 [27886] <2> bptm: INITIATING (VERBOSE = 0): -rptdrv -jobid -1303437601 -jm
11:56:10.617 [27886] <2> bptm: EMMserver_name = bckserver01
11:56:10.617 [27886] <2> bptm: EMMserver_port = 1556
11:56:10.617 [27886] <2> drivename_open: Called with Create 0, file IBM.ULTRIUM-TD3.000
11:56:10.618 [27886] <2> drivename_checklock: Called
11:56:10.618 [27886] <2> drivename_checklock: PID 27396 has lock
11:56:10.618 [27886] <2> report_drives: MODE = 0
11:56:10.618 [27886] <2> report_drives: TIME = 1304393842
11:56:10.618 [27886] <2> report_drives: MASTER = bckserver01
11:56:10.618 [27886] <2> report_drives: PATH = /dev/rmt/0cbn
11:56:10.618 [27886] <2> report_drives: MEDIA = 0019L3
11:56:10.618 [27886] <2> report_drives: REQID = -1303437597
11:56:10.618 [27886] <2> report_drives: ALOCID = 89554
11:56:10.618 [27886] <2> report_drives: RBID = {A1298978-1DD1-11B2-8BF1-00144F8FEBCA}
11:56:10.618 [27886] <2> report_drives: PID = 27396
11:56:10.618 [27886] <2> report_drives: FILE = /usr/openv/netbackup/db/media/tpreq/drive_IBM.ULTRIUM-TD3.000
11:56:10.618 [27886] <2> main: Sending [EXIT STATUS 0] to NBJM
11:56:10.618 [27886] <2> bptm: EXITING with status 0 <----------
11:59:42.088 [27971] <2> bptm: INITIATING (VERBOSE = 0): -delete_expired
11:59:42.098 [27971] <2> bptm: EXITING with status 0 <----------
11:59:42.599 [27975] <2> bptm: INITIATING (VERBOSE = 0): -delete_all_expired
11:59:42.608 [27975] <2> bptm: EMMserver_name = bckserver01
11:59:42.608 [27975] <2> bptm: EMMserver_port = 1556
11:59:42.672 [27975] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:59:42.672 [27975] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:59:42.681 [27975] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name bckserver01
11:59:42.787 [27975] <2> bptm: EXITING with status 0 <----------
12:06:10.217 [28145] <2> bptm: INITIATING (VERBOSE = 0): -rptdrv -jobid -1303437604 -jm
12:06:10.229 [28145] <2> bptm: EMMserver_name = bckserver01
12:06:10.229 [28145] <2> bptm: EMMserver_port = 1556
12:06:10.230 [28145] <2> drivename_open: Called with Create 0, file IBM.ULTRIUM-TD3.000
12:06:10.230 [28145] <2> drivename_checklock: Called
12:06:10.230 [28145] <2> drivename_checklock: PID 27396 has lock
12:06:10.230 [28145] <2> report_drives: MODE = 0
12:06:10.231 [28145] <2> report_drives: TIME = 1304393842
12:06:10.231 [28145] <2> report_drives: MASTER = bckserver01
12:06:10.231 [28145] <2> report_drives: PATH = /dev/rmt/0cbn
12:06:10.231 [28145] <2> report_drives: MEDIA = 0019L3
12:06:10.231 [28145] <2> report_drives: REQID = -1303437597
12:06:10.231 [28145] <2> report_drives: ALOCID = 89554
12:06:10.231 [28145] <2> report_drives: RBID = {A1298978-1DD1-11B2-8BF1-00144F8FEBCA}
12:06:10.231 [28145] <2> report_drives: PID = 27396
12:06:10.231 [28145] <2> report_drives: FILE = /usr/openv/netbackup/db/media/tpreq/drive_IBM.ULTRIUM-TD3.000
12:06:10.233 [28145] <2> main: Sending [EXIT STATUS 0] to NBJM
12:06:10.233 [28145] <2> bptm: EXITING with status 0 <----------
12:09:43.541 [28233] <2> bptm: INITIATING (VERBOSE = 0): -delete_expired
12:09:43.550 [28233] <2> bptm: EXITING with status 0 <----------
12:09:44.039 [28237] <2> bptm: INITIATING (VERBOSE = 0): -delete_all_expired
12:09:44.049 [28237] <2> bptm: EMMserver_name = bckserver01
12:09:44.049 [28237] <2> bptm: EMMserver_port = 1556
12:09:44.114 [28237] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
12:09:44.115 [28237] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
12:09:44.125 [28237] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name bckserver01
12:09:44.232 [28237] <2> bptm: EXITING with status 0 <----------
12:16:10.213 [28402] <2> bptm: INITIATING (VERBOSE = 0): -rptdrv -jobid -1303437607 -jm
12:16:10.222 [28402] <2> bptm: EMMserver_name = bckserver01
12:16:10.222 [28402] <2> bptm: EMMserver_port = 1556
12:16:10.223 [28402] <2> drivename_open: Called with Create 0, file IBM.ULTRIUM-TD3.000
12:16:10.223 [28402] <2> drivename_checklock: Called
12:16:10.223 [28402] <2> drivename_checklock: PID 27396 has lock
12:16:10.223 [28402] <2> report_drives: MODE = 0
12:16:10.223 [28402] <2> report_drives: TIME = 1304393842
12:16:10.223 [28402] <2> report_drives: MASTER = bckserver01
12:16:10.223 [28402] <2> report_drives: PATH = /dev/rmt/0cbn
12:16:10.223 [28402] <2> report_drives: MEDIA = 0019L3
12:16:10.223 [28402] <2> report_drives: REQID = -1303437597
12:16:10.223 [28402] <2> report_drives: ALOCID = 89554
12:16:10.223 [28402] <2> report_drives: RBID = {A1298978-1DD1-11B2-8BF1-00144F8FEBCA}
12:16:10.223 [28402] <2> report_drives: PID = 27396
12:16:10.223 [28402] <2> report_drives: FILE = /usr/openv/netbackup/db/media/tpreq/drive_IBM.ULTRIUM-TD3.000
12:16:10.224 [28402] <2> main: Sending [EXIT STATUS 0] to NBJM
12:16:10.224 [28402] <2> bptm: EXITING with status 0 <----------
12:19:44.961 [28482] <2> bptm: INITIATING (VERBOSE = 0): -delete_expired
12:19:44.971 [28482] <2> bptm: EXITING with status 0 <----------
12:19:45.471 [28486] <2> bptm: INITIATING (VERBOSE = 0): -delete_all_expired
12:19:45.481 [28486] <2> bptm: EMMserver_name = bckserver01
12:19:45.481 [28486] <2> bptm: EMMserver_port = 1556
12:19:45.546 [28486] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
12:19:45.546 [28486] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
12:19:45.555 [28486] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name bckserver01
12:19:45.658 [28486] <2> bptm: EXITING with status 0 <----------
12:26:10.245 [28658] <2> bptm: INITIATING (VERBOSE = 0): -rptdrv -jobid -1303437610 -jm
12:26:10.254 [28658] <2> bptm: EMMserver_name = bckserver01
12:26:10.254 [28658] <2> bptm: EMMserver_port = 1556
12:26:10.254 [28658] <2> drivename_open: Called with Create 0, file IBM.ULTRIUM-TD3.000
12:26:10.254 [28658] <2> drivename_checklock: Called
12:26:10.254 [28658] <2> drivename_checklock: PID 27396 has lock
12:26:10.254 [28658] <2> report_drives: MODE = 0
12:26:10.254 [28658] <2> report_drives: TIME = 1304393842
12:26:10.254 [28658] <2> report_drives: MASTER = bckserver01
12:26:10.254 [28658] <2> report_drives: PATH = /dev/rmt/0cbn
12:26:10.254 [28658] <2> report_drives: MEDIA = 0019L3
12:26:10.254 [28658] <2> report_drives: REQID = -1303437597
12:26:10.254 [28658] <2> report_drives: ALOCID = 89554
12:26:10.254 [28658] <2> report_drives: RBID = {A1298978-1DD1-11B2-8BF1-00144F8FEBCA}
12:26:10.254 [28658] <2> report_drives: PID = 27396
12:26:10.255 [28658] <2> report_drives: FILE = /usr/openv/netbackup/db/media/tpreq/drive_IBM.ULTRIUM-TD3.000
12:26:10.255 [28658] <2> main: Sending [EXIT STATUS 0] to NBJM
12:26:10.255 [28658] <2> bptm: EXITING with status 0 <----------
12:29:46.393 [28743] <2> bptm: INITIATING (VERBOSE = 0): -delete_expired
12:29:46.403 [28743] <2> bptm: EXITING with status 0 <----------
12:29:46.894 [28747] <2> bptm: INITIATING (VERBOSE = 0): -delete_all_expired
12:29:46.904 [28747] <2> bptm: EMMserver_name = bckserver01
12:29:46.904 [28747] <2> bptm: EMMserver_port = 1556
12:29:46.966 [28747] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
12:29:46.966 [28747] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
12:29:46.976 [28747] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name bckserver01
12:29:47.080 [28747] <2> bptm: EXITING with status 0 <----------
12:36:10.199 [28913] <2> bptm: INITIATING (VERBOSE = 0): -rptdrv -jobid -1303437613 -jm
12:36:10.209 [28913] <2> bptm: EMMserver_name = bckserver01
12:36:10.209 [28913] <2> bptm: EMMserver_port = 1556
12:36:10.209 [28913] <2> drivename_open: Called with Create 0, file IBM.ULTRIUM-TD3.000
12:36:10.209 [28913] <2> drivename_checklock: Called
12:36:10.209 [28913] <2> drivename_checklock: PID 27396 has lock
12:36:10.210 [28913] <2> report_drives: MODE = 0
12:36:10.210 [28913] <2> report_drives: TIME = 1304393842
12:36:10.210 [28913] <2> report_drives: MASTER = bckserver01
12:36:10.210 [28913] <2> report_drives: PATH = /dev/rmt/0cbn
12:36:10.210 [28913] <2> report_drives: MEDIA = 0019L3
12:36:10.210 [28913] <2> report_drives: REQID = -1303437597
12:36:10.210 [28913] <2> report_drives: ALOCID = 89554
12:36:10.210 [28913] <2> report_drives: RBID = {A1298978-1DD1-11B2-8BF1-00144F8FEBCA}
12:36:10.210 [28913] <2> report_drives: PID = 27396
12:36:10.210 [28913] <2> report_drives: FILE = /usr/openv/netbackup/db/media/tpreq/drive_IBM.ULTRIUM-TD3.000
12:36:10.210 [28913] <2> main: Sending [EXIT STATUS 0] to NBJM
12:36:10.211 [28913] <2> bptm: EXITING with status 0 <----------
12:39:47.811 [29000] <2> bptm: INITIATING (VERBOSE = 0): -delete_expired
12:39:47.821 [29000] <2> bptm: EXITING with status 0 <----------
12:39:48.315 [29004] <2> bptm: INITIATING (VERBOSE = 0): -delete_all_expired
12:39:48.324 [29004] <2> bptm: EMMserver_name = bckserver01
12:39:48.324 [29004] <2> bptm: EMMserver_port = 1556
12:39:48.392 [29004] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
12:39:48.392 [29004] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
12:39:48.402 [29004] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name bckserver01
12:39:48.513 [29004] <2> bptm: EXITING with status 0 <----------
12:46:10.200 [29169] <2> bptm: INITIATING (VERBOSE = 0): -rptdrv -jobid -1303437616 -jm
12:46:10.210 [29169] <2> bptm: EMMserver_name = bckserver01
12:46:10.210 [29169] <2> bptm: EMMserver_port = 1556
12:46:10.210 [29169] <2> drivename_open: Called with Create 0, file IBM.ULTRIUM-TD3.000
12:46:10.210 [29169] <2> drivename_checklock: Called
12:46:10.210 [29169] <2> drivename_checklock: PID 27396 has lock
12:46:10.210 [29169] <2> report_drives: MODE = 0
12:46:10.211 [29169] <2> report_drives: TIME = 1304393842
12:46:10.211 [29169] <2> report_drives: MASTER = bckserver01
12:46:10.211 [29169] <2> report_drives: PATH = /dev/rmt/0cbn
12:46:10.211 [29169] <2> report_drives: MEDIA = 0019L3
12:46:10.211 [29169] <2> report_drives: REQID = -1303437597
12:46:10.211 [29169] <2> report_drives: ALOCID = 89554
12:46:10.211 [29169] <2> report_drives: RBID = {A1298978-1DD1-11B2-8BF1-00144F8FEBCA}
12:46:10.211 [29169] <2> report_drives: PID = 27396
12:46:10.211 [29169] <2> report_drives: FILE = /usr/openv/netbackup/db/media/tpreq/drive_IBM.ULTRIUM-TD3.000
12:46:10.211 [29169] <2> main: Sending [EXIT STATUS 0] to NBJM
12:46:10.212 [29169] <2> bptm: EXITING with status 0 <----------
12:49:49.216 [29254] <2> bptm: INITIATING (VERBOSE = 0): -delete_expired
12:49:49.225 [29254] <2> bptm: EXITING with status 0 <----------
12:49:49.712 [29258] <2> bptm: INITIATING (VERBOSE = 0): -delete_all_expired
12:49:49.722 [29258] <2> bptm: EMMserver_name = bckserver01
12:49:49.722 [29258] <2> bptm: EMMserver_port = 1556
12:49:49.784 [29258] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
12:49:49.784 [29258] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
12:49:49.793 [29258] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name bckserver01
12:49:49.901 [29258] <2> bptm: EXITING with status 0 <----------
12:56:10.241 [29425] <2> bptm: INITIATING (VERBOSE = 0): -rptdrv -jobid -1303437619 -jm
12:56:10.251 [29425] <2> bptm: EMMserver_name = bckserver01
12:56:10.251 [29425] <2> bptm: EMMserver_port = 1556
12:56:10.251 [29425] <2> drivename_open: Called with Create 0, file IBM.ULTRIUM-TD3.000
12:56:10.251 [29425] <2> drivename_checklock: Called
12:56:10.252 [29425] <2> drivename_checklock: PID 27396 has lock
12:56:10.252 [29425] <2> report_drives: MODE = 0
12:56:10.252 [29425] <2> report_drives: TIME = 1304393842
12:56:10.252 [29425] <2> report_drives: MASTER = bckserver01
12:56:10.252 [29425] <2> report_drives: PATH = /dev/rmt/0cbn
12:56:10.252 [29425] <2> report_drives: MEDIA = 0019L3
12:56:10.252 [29425] <2> report_drives: REQID = -1303437597
12:56:10.252 [29425] <2> report_drives: ALOCID = 89554
12:56:10.252 [29425] <2> report_drives: RBID = {A1298978-1DD1-11B2-8BF1-00144F8FEBCA}
12:56:10.252 [29425] <2> report_drives: PID = 27396
12:56:10.252 [29425] <2> report_drives: FILE = /usr/openv/netbackup/db/media/tpreq/drive_IBM.ULTRIUM-TD3.000
12:56:10.254 [29425] <2> main: Sending [EXIT STATUS 0] to NBJM
12:56:10.255 [29425] <2> bptm: EXITING with status 0 <----------
12:59:50.661 [29510] <2> bptm: INITIATING (VERBOSE = 0): -delete_expired
12:59:50.670 [29510] <2> bptm: EXITING with status 0 <----------
12:59:51.169 [29514] <2> bptm: INITIATING (VERBOSE = 0): -delete_all_expired
12:59:51.178 [29514] <2> bptm: EMMserver_name = bckserver01
12:59:51.178 [29514] <2> bptm: EMMserver_port = 1556
12:59:51.240 [29514] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
12:59:51.240 [29514] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
12:59:51.250 [29514] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name bckserver01
12:59:51.359 [29514] <2> bptm: EXITING with status 0 <----------
13:06:10.190 [29724] <2> bptm: INITIATING (VERBOSE = 0): -rptdrv -jobid -1303437622 -jm
13:06:10.199 [29724] <2> bptm: EMMserver_name = bckserver01
13:06:10.199 [29724] <2> bptm: EMMserver_port = 1556
13:06:10.199 [29724] <2> drivename_open: Called with Create 0, file IBM.ULTRIUM-TD3.000
13:06:10.200 [29724] <2> drivename_checklock: Called
13:06:10.200 [29724] <2> drivename_checklock: PID 27396 has lock
13:06:10.200 [29724] <2> report_drives: MODE = 0
13:06:10.200 [29724] <2> report_drives: TIME = 1304393842
13:06:10.200 [29724] <2> report_drives: MASTER = bckserver01
13:06:10.200 [29724] <2> report_drives: PATH = /dev/rmt/0cbn
13:06:10.200 [29724] <2> report_drives: MEDIA = 0019L3
13:06:10.200 [29724] <2> report_drives: REQID = -1303437597
13:06:10.200 [29724] <2> report_drives: ALOCID = 89554
13:06:10.200 [29724] <2> report_drives: RBID = {A1298978-1DD1-11B2-8BF1-00144F8FEBCA}
13:06:10.200 [29724] <2> report_drives: PID = 27396
13:06:10.200 [29724] <2> report_drives: FILE = /usr/openv/netbackup/db/media/tpreq/drive_IBM.ULTRIUM-TD3.000
13:06:10.201 [29724] <2> main: Sending [EXIT STATUS 0] to NBJM
13:06:10.201 [29724] <2> bptm: EXITING with status 0 <----------
13:09:52.090 [29807] <2> bptm: INITIATING (VERBOSE = 0): -delete_expired
13:09:52.100 [29807] <2> bptm: EXITING with status 0 <----------
13:09:52.610 [29811] <2> bptm: INITIATING (VERBOSE = 0): -delete_all_expired
13:09:52.619 [29811] <2> bptm: EMMserver_name = bckserver01
13:09:52.619 [29811] <2> bptm: EMMserver_port = 1556
13:09:52.686 [29811] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
13:09:52.686 [29811] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
13:09:52.696 [29811] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name bckserver01
13:09:52.801 [29811] <2> bptm: EXITING with status 0 <----------
13:16:10.162 [29975] <2> bptm: INITIATING (VERBOSE = 0): -rptdrv -jobid -1303437625 -jm
13:16:10.170 [29975] <2> bptm: EMMserver_name = bckserver01
13:16:10.171 [29975] <2> bptm: EMMserver_port = 1556
13:16:10.171 [29975] <2> drivename_open: Called with Create 0, file IBM.ULTRIUM-TD3.000
13:16:10.171 [29975] <2> drivename_checklock: Called
13:16:10.171 [29975] <2> drivename_checklock: PID 27396 has lock
13:16:10.171 [29975] <2> report_drives: MODE = 0
13:16:10.171 [29975] <2> report_drives: TIME = 1304393842
13:16:10.171 [29975] <2> report_drives: MASTER = bckserver01
13:16:10.171 [29975] <2> report_drives: PATH = /dev/rmt/0cbn
13:16:10.171 [29975] <2> report_drives: MEDIA = 0019L3
13:16:10.171 [29975] <2> report_drives: REQID = -1303437597
13:16:10.171 [29975] <2> report_drives: ALOCID = 89554
13:16:10.171 [29975] <2> report_drives: RBID = {A1298978-1DD1-11B2-8BF1-00144F8FEBCA}
13:16:10.171 [29975] <2> report_drives: PID = 27396
13:16:10.171 [29975] <2> report_drives: FILE = /usr/openv/netbackup/db/media/tpreq/drive_IBM.ULTRIUM-TD3.000
13:16:10.172 [29975] <2> main: Sending [EXIT STATUS 0] to NBJM
13:16:10.172 [29975] <2> bptm: EXITING with status 0 <----------
13:19:53.545 [66] <2> bptm: INITIATING (VERBOSE = 0): -delete_expired
13:19:53.554 [66] <2> bptm: EXITING with status 0 <----------
13:19:54.046 [70] <2> bptm: INITIATING (VERBOSE = 0): -delete_all_expired
13:19:54.055 [70] <2> bptm: EMMserver_name = bckserver01
13:19:54.055 [70] <2> bptm: EMMserver_port = 1556
13:19:54.119 [70] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
13:19:54.119 [70] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
13:19:54.128 [70] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name bckserver01
13:19:54.238 [70] <2> bptm: EXITING with status 0 <----------
13:26:10.201 [240] <2> bptm: INITIATING (VERBOSE = 0): -rptdrv -jobid -1303437628 -jm
13:26:10.211 [240] <2> bptm: EMMserver_name = bckserver01
13:26:10.211 [240] <2> bptm: EMMserver_port = 1556
13:26:10.212 [240] <2> drivename_open: Called with Create 0, file IBM.ULTRIUM-TD3.000
13:26:10.212 [240] <2> drivename_checklock: Called
13:26:10.212 [240] <2> drivename_checklock: PID 27396 has lock
13:26:10.212 [240] <2> report_drives: MODE = 0
13:26:10.212 [240] <2> report_drives: TIME = 1304393842
13:26:10.212 [240] <2> report_drives: MASTER = bckserver01
13:26:10.212 [240] <2> report_drives: PATH = /dev/rmt/0cbn
13:26:10.212 [240] <2> report_drives: MEDIA = 0019L3
13:26:10.212 [240] <2> report_drives: REQID = -1303437597
13:26:10.212 [240] <2> report_drives: ALOCID = 89554
13:26:10.212 [240] <2> report_drives: RBID = {A1298978-1DD1-11B2-8BF1-00144F8FEBCA}
13:26:10.212 [240] <2> report_drives: PID = 27396
13:26:10.212 [240] <2> report_drives: FILE = /usr/openv/netbackup/db/media/tpreq/drive_IBM.ULTRIUM-TD3.000
13:26:10.213 [240] <2> main: Sending [EXIT STATUS 0] to NBJM
13:26:10.213 [240] <2> bptm: EXITING with status 0 <----------
13:29:55.011 [338] <2> bptm: INITIATING (VERBOSE = 0): -delete_expired
13:29:55.021 [338] <2> bptm: EXITING with status 0 <----------
13:29:55.525 [343] <2> bptm: INITIATING (VERBOSE = 0): -delete_all_expired
13:29:55.535 [343] <2> bptm: EMMserver_name = bckserver01
13:29:55.535 [343] <2> bptm: EMMserver_port = 1556
13:29:55.602 [343] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
13:29:55.602 [343] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
13:29:55.612 [343] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name bckserver01
13:29:55.724 [343] <2> bptm: EXITING with status 0 <----------
13:36:10.159 [556] <2> bptm: INITIATING (VERBOSE = 0): -rptdrv -jobid -1303437631 -jm
13:36:10.169 [556] <2> bptm: EMMserver_name = bckserver01
13:36:10.169 [556] <2> bptm: EMMserver_port = 1556
13:36:10.169 [556] <2> drivename_open: Called with Create 0, file IBM.ULTRIUM-TD3.000
13:36:10.170 [556] <2> drivename_checklock: Called
13:36:10.170 [556] <2> drivename_checklock: PID 27396 has lock
13:36:10.170 [556] <2> report_drives: MODE = 0
13:36:10.170 [556] <2> report_drives: TIME = 1304393842
13:36:10.170 [556] <2> report_drives: MASTER = bckserver01
13:36:10.170 [556] <2> report_drives: PATH = /dev/rmt/0cbn
13:36:10.170 [556] <2> report_drives: MEDIA = 0019L3
13:36:10.170 [556] <2> report_drives: REQID = -1303437597
13:36:10.170 [556] <2> report_drives: ALOCID = 89554
13:36:10.170 [556] <2> report_drives: RBID = {A1298978-1DD1-11B2-8BF1-00144F8FEBCA}
13:36:10.170 [556] <2> report_drives: PID = 27396
13:36:10.170 [556] <2> report_drives: FILE = /usr/openv/netbackup/db/media/tpreq/drive_IBM.ULTRIUM-TD3.000
13:36:10.170 [556] <2> main: Sending [EXIT STATUS 0] to NBJM
13:36:10.171 [556] <2> bptm: EXITING with status 0 <----------
13:39:56.463 [646] <2> bptm: INITIATING (VERBOSE = 0): -delete_expired
13:39:56.473 [646] <2> bptm: EXITING with status 0 <----------
13:39:56.980 [650] <2> bptm: INITIATING (VERBOSE = 0): -delete_all_expired
13:39:56.989 [650] <2> bptm: EMMserver_name = bckserver01
13:39:56.989 [650] <2> bptm: EMMserver_port = 1556
13:39:57.066 [650] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
13:39:57.066 [650] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
13:39:57.076 [650] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name bckserver01
13:39:57.177 [650] <2> bptm: EXITING with status 0 <----------
13:46:10.237 [827] <2> bptm: INITIATING (VERBOSE = 0): -rptdrv -jobid -1303437634 -jm
13:46:10.247 [827] <2> bptm: EMMserver_name = bckserver01
13:46:10.247 [827] <2> bptm: EMMserver_port = 1556
13:46:10.248 [827] <2> drivename_open: Called with Create 0, file IBM.ULTRIUM-TD3.000
13:46:10.248 [827] <2> drivename_checklock: Called
13:46:10.248 [827] <2> drivename_checklock: PID 27396 has lock
13:46:10.248 [827] <2> report_drives: MODE = 0
13:46:10.248 [827] <2> report_drives: TIME = 1304393842
13:46:10.248 [827] <2> report_drives: MASTER = bckserver01
13:46:10.248 [827] <2> report_drives: PATH = /dev/rmt/0cbn
13:46:10.248 [827] <2> report_drives: MEDIA = 0019L3
13:46:10.248 [827] <2> report_drives: REQID = -1303437597
13:46:10.248 [827] <2> report_drives: ALOCID = 89554
13:46:10.248 [827] <2> report_drives: RBID = {A1298978-1DD1-11B2-8BF1-00144F8FEBCA}
13:46:10.248 [827] <2> report_drives: PID = 27396
13:46:10.248 [827] <2> report_drives: FILE = /usr/openv/netbackup/db/media/tpreq/drive_IBM.ULTRIUM-TD3.000
13:46:10.249 [827] <2> main: Sending [EXIT STATUS 0] to NBJM
13:46:10.249 [827] <2> bptm: EXITING with status 0 <----------
13:49:57.918 [954] <2> bptm: INITIATING (VERBOSE = 0): -delete_expired
13:49:57.927 [954] <2> bptm: EXITING with status 0 <----------
13:49:58.408 [958] <2> bptm: INITIATING (VERBOSE = 0): -delete_all_expired
13:49:58.417 [958] <2> bptm: EMMserver_name = bckserver01
13:49:58.417 [958] <2> bptm: EMMserver_port = 1556
13:49:58.480 [958] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
13:49:58.481 [958] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
13:49:58.491 [958] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name bckserver01
13:49:58.599 [958] <2> bptm: EXITING with status 0 <----------
13:56:10.214 [1111] <2> bptm: INITIATING (VERBOSE = 0): -rptdrv -jobid -1303437637 -jm
13:56:10.224 [1111] <2> bptm: EMMserver_name = bckserver01
13:56:10.224 [1111] <2> bptm: EMMserver_port = 1556
13:56:10.225 [1111] <2> drivename_open: Called with Create 0, file IBM.ULTRIUM-TD3.000
13:56:10.225 [1111] <2> drivename_checklock: Called
13:56:10.225 [1111] <2> drivename_checklock: PID 27396 has lock
13:56:10.225 [1111] <2> report_drives: MODE = 0
13:56:10.225 [1111] <2> report_drives: TIME = 1304393842
13:56:10.225 [1111] <2> report_drives: MASTER = bckserver01
13:56:10.225 [1111] <2> report_drives: PATH = /dev/rmt/0cbn
13:56:10.225 [1111] <2> report_drives: MEDIA = 0019L3
13:56:10.225 [1111] <2> report_drives: REQID = -1303437597
13:56:10.225 [1111] <2> report_drives: ALOCID = 89554
13:56:10.225 [1111] <2> report_drives: RBID = {A1298978-1DD1-11B2-8BF1-00144F8FEBCA}
13:56:10.225 [1111] <2> report_drives: PID = 27396
13:56:10.225 [1111] <2> report_drives: FILE = /usr/openv/netbackup/db/media/tpreq/drive_IBM.ULTRIUM-TD3.000
13:56:10.226 [1111] <2> main: Sending [EXIT STATUS 0] to NBJM
13:56:10.226 [1111] <2> bptm: EXITING with status 0 <----------
13:59:59.314 [1207] <2> bptm: INITIATING (VERBOSE = 0): -delete_expired
13:59:59.324 [1207] <2> bptm: EXITING with status 0 <----------
13:59:59.817 [1211] <2> bptm: INITIATING (VERBOSE = 0): -delete_all_expired
13:59:59.827 [1211] <2> bptm: EMMserver_name = bckserver01
13:59:59.827 [1211] <2> bptm: EMMserver_port = 1556
13:59:59.888 [1211] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
13:59:59.888 [1211] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
13:59:59.897 [1211] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name bckserver01
14:00:00.001 [1211] <2> bptm: EXITING with status 0 <----------
14:06:10.204 [1376] <2> bptm: INITIATING (VERBOSE = 0): -rptdrv -jobid -1303437640 -jm
14:06:10.215 [1376] <2> bptm: EMMserver_name = bckserver01
14:06:10.215 [1376] <2> bptm: EMMserver_port = 1556
14:06:10.216 [1376] <2> drivename_open: Called with Create 0, file IBM.ULTRIUM-TD3.000
14:06:10.216 [1376] <2> drivename_checklock: Called
14:06:10.216 [1376] <2> drivename_checklock: PID 27396 has lock
14:06:10.216 [1376] <2> report_drives: MODE = 0
14:06:10.216 [1376] <2> report_drives: TIME = 1304393842
14:06:10.216 [1376] <2> report_drives: MASTER = bckserver01
14:06:10.216 [1376] <2> report_drives: PATH = /dev/rmt/0cbn
14:06:10.216 [1376] <2> report_drives: MEDIA = 0019L3
14:06:10.216 [1376] <2> report_drives: REQID = -1303437597
14:06:10.216 [1376] <2> report_drives: ALOCID = 89554
14:06:10.216 [1376] <2> report_drives: RBID = {A1298978-1DD1-11B2-8BF1-00144F8FEBCA}
14:06:10.216 [1376] <2> report_drives: PID = 27396
14:06:10.216 [1376] <2> report_drives: FILE = /usr/openv/netbackup/db/media/tpreq/drive_IBM.ULTRIUM-TD3.000
14:06:10.219 [1376] <2> main: Sending [EXIT STATUS 0] to NBJM
14:06:10.219 [1376] <2> bptm: EXITING with status 0 <----------
14:10:00.759 [1464] <2> bptm: INITIATING (VERBOSE = 0): -delete_expired
14:10:00.768 [1464] <2> bptm: EXITING with status 0 <----------
14:10:01.258 [1483] <2> bptm: INITIATING (VERBOSE = 0): -delete_all_expired
14:10:01.267 [1483] <2> bptm: EMMserver_name = bckserver01
14:10:01.267 [1483] <2> bptm: EMMserver_port = 1556
14:10:01.330 [1483] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
14:10:01.330 [1483] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
14:10:01.339 [1483] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name bckserver01
14:10:01.444 [1483] <2> bptm: EXITING with status 0 <----------
14:16:10.243 [1633] <2> bptm: INITIATING (VERBOSE = 0): -rptdrv -jobid -1303437643 -jm
14:16:10.252 [1633] <2> bptm: EMMserver_name = bckserver01
14:16:10.252 [1633] <2> bptm: EMMserver_port = 1556
14:16:10.252 [1633] <2> drivename_open: Called with Create 0, file IBM.ULTRIUM-TD3.000
14:16:10.252 [1633] <2> drivename_checklock: Called
14:16:10.252 [1633] <2> drivename_checklock: PID 27396 has lock
14:16:10.253 [1633] <2> report_drives: MODE = 0
14:16:10.253 [1633] <2> report_drives: TIME = 1304393842
14:16:10.253 [1633] <2> report_drives: MASTER = bckserver01
14:16:10.253 [1633] <2> report_drives: PATH = /dev/rmt/0cbn
14:16:10.253 [1633] <2> report_drives: MEDIA = 0019L3
14:16:10.253 [1633] <2> report_drives: REQID = -1303437597
14:16:10.253 [1633] <2> report_drives: ALOCID = 89554
14:16:10.253 [1633] <2> report_drives: RBID = {A1298978-1DD1-11B2-8BF1-00144F8FEBCA}
14:16:10.253 [1633] <2> report_drives: PID = 27396
14:16:10.253 [1633] <2> report_drives: FILE = /usr/openv/netbackup/db/media/tpreq/drive_IBM.ULTRIUM-TD3.000
14:16:10.254 [1633] <2> main: Sending [EXIT STATUS 0] to NBJM
14:16:10.254 [1633] <2> bptm: EXITING with status 0 <----------
14:20:02.166 [1933] <2> bptm: INITIATING (VERBOSE = 0): -delete_expired
14:20:02.175 [1933] <2> bptm: EXITING with status 0 <----------
14:20:02.667 [1937] <2> bptm: INITIATING (VERBOSE = 0): -delete_all_expired
14:20:02.676 [1937] <2> bptm: EMMserver_name = bckserver01
14:20:02.676 [1937] <2> bptm: EMMserver_port = 1556
14:20:02.741 [1937] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
14:20:02.741 [1937] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
14:20:02.751 [1937] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name bckserver01
14:20:02.860 [1937] <2> bptm: EXITING with status 0 <----------
14:26:10.247 [2110] <2> bptm: INITIATING (VERBOSE = 0): -rptdrv -jobid -1303437646 -jm
14:26:10.257 [2110] <2> bptm: EMMserver_name = bckserver01
14:26:10.257 [2110] <2> bptm: EMMserver_port = 1556
14:26:10.258 [2110] <2> drivename_open: Called with Create 0, file IBM.ULTRIUM-TD3.000
14:26:10.258 [2110] <2> drivename_checklock: Called
14:26:10.258 [2110] <2> drivename_checklock: PID 27396 has lock
14:26:10.258 [2110] <2> report_drives: MODE = 0
14:26:10.258 [2110] <2> report_drives: TIME = 1304393842
14:26:10.258 [2110] <2> report_drives: MASTER = bckserver01
14:26:10.258 [2110] <2> report_drives: PATH = /dev/rmt/0cbn
14:26:10.258 [2110] <2> report_drives: MEDIA = 0019L3
14:26:10.258 [2110] <2> report_drives: REQID = -1303437597
14:26:10.258 [2110] <2> report_drives: ALOCID = 89554
14:26:10.258 [2110] <2> report_drives: RBID = {A1298978-1DD1-11B2-8BF1-00144F8FEBCA}
14:26:10.258 [2110] <2> report_drives: PID = 27396
14:26:10.258 [2110] <2> report_drives: FILE = /usr/openv/netbackup/db/media/tpreq/drive_IBM.ULTRIUM-TD3.000
14:26:10.259 [2110] <2> main: Sending [EXIT STATUS 0] to NBJM
14:26:10.259 [2110] <2> bptm: EXITING with status 0 <----------


bpbrm log on mediaserver:

11:37:13.585 [27395] <2> logparams: -backup -S bckserver01 -c 10.67.99.227 -ct 13 -ru root -cl File_10.67.99.227 -sched Full -bt 1304393831 -dt 0 -st 0 -b 10.67.99.227_1304393831 -mediasvr bckserver01 -jobid 147385 -jobgrpid 147385 -masterversion 600000 -maxfrag 1048576 -reqid -1303437597 -mt 2 -to 0 -stunit bckserver01-hcart2-robot-tld-0 -cj 2 -rl 1 -rp 1209600 -D 14 -rt 8 -rn 0 -pool NetBackup -use_ofb -use_otm -WOFB_enabled 1 -nbu -jm -secure 1 -rg root -fso
11:37:13.611 [27395] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2034: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006
11:37:13.611 [27395] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2048: service: bpcd
11:37:13.719 [27395] <2> logconnections: BPCD CONNECT FROM 10.87.0.198.58871 TO 10.67.99.227.13724
11:37:13.720 [27395] <2> vnet_connect_to_vnetd_extra: vnet_vnetd.c.178: msg: VNETD CONNECT FROM 10.87.0.198.58873 TO 10.67.99.227.13724 fd = 7
11:37:13.733 [27395] <2> vnet_vnetd_connect_forward_socket_end: vnet_vnetd.c.529: VN_REQUEST_CONNECT_FORWARD_SOCKET: 10 0x0000000a
11:37:13.919 [27395] <2> vnet_vnetd_connect_forward_socket_end: vnet_vnetd.c.546: ipc_string: 4151
11:37:14.232 [27395] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:37:14.232 [27395] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:37:14.285 [27395] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:37:14.285 [27395] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:37:14.334 [27395] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2034: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006
11:37:14.334 [27395] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2048: service: bpcd
11:37:14.626 [27395] <2> logconnections: BPCD CONNECT FROM 10.87.0.198.58880 TO 10.67.99.227.13724
11:37:14.626 [27395] <2> vnet_connect_to_vnetd_extra: vnet_vnetd.c.178: msg: VNETD CONNECT FROM 10.87.0.198.58881 TO 10.67.99.227.13724 fd = 7
11:37:14.639 [27395] <2> vnet_vnetd_connect_forward_socket_end: vnet_vnetd.c.529: VN_REQUEST_CONNECT_FORWARD_SOCKET: 10 0x0000000a
11:37:14.824 [27395] <2> vnet_vnetd_connect_forward_socket_end: vnet_vnetd.c.546: ipc_string: 4153
11:37:15.131 [27395] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:37:15.131 [27395] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:37:15.153 [27395] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:37:15.153 [27395] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:37:15.220 [27395] <2> local_getAllBEorNBHostInfo: CLIENT_CMD_SOCK from bpcr = 6
11:37:15.320 [27395] <2> bpcr_get_version_rqst: bpcd version: 06000404
11:37:15.530 [27395] <2> local_getAllBEorNBHostInfo: hostname comparison failed
11:37:15.630 [27395] <2> bpcr_get_version_rqst: bpcd version: 06000404
11:37:15.630 [27395] <2> local_getAllBEorNBHostInfo: rqstType == BPCD_GET_NB_VERSION_RQST
11:37:16.131 [27395] <2> bpcr_get_platform_rqst: Server client platform length = 2
11:37:16.230 [27395] <2> xlatePlatformName: my version = 600000, client version = 600000, check current list first
11:37:16.230 [27395] <2> xlatePlatformName: BUILD_PLATFORM name to xlate = nt
11:37:16.230 [27395] <2> local_getAllBEorNBHostInfo: platform of client 10.67.99.227 is PC, Windows2000
11:37:16.430 [27395] <2> bpcr_get_uname_rqst: bpcr.c: 3754 count=27, buffer=UNAME_SYSNAME = WindowsNET
11:37:16.430 [27395] <2> bpcr_get_uname_rqst: bpcr.c: 3754 count=23, buffer=UNAME_NODENAME = CCCCE
11:37:16.430 [27395] <2> bpcr_get_uname_rqst: bpcr.c: 3754 count=18, buffer=UNAME_RELEASE = 5
11:37:16.430 [27395] <2> bpcr_get_uname_rqst: bpcr.c: 3754 count=18, buffer=UNAME_VERSION = 2
11:37:16.430 [27395] <2> bpcr_get_uname_rqst: bpcr.c: 3754 count=62, buffer=UNAME_MACHINE = Intel x86 - Intel Pentium Pro - Revision xf06
11:37:16.430 [27395] <2> bpcr_get_uname_rqst: bpcr.c: 3754 count=3, buffer=END
11:37:16.430 [27395] <2> local_getAllBEorNBHostInfo: dbmisc.c: 1640, uname -sr=WindowsNET 5

11:37:16.535 [27395] <2> bpcr_get_version_rqst: bpcd version: 06000404
11:37:17.030 [27395] <2> getPatchLevel: CLIENT_CMD_SOCK from bpcr = 6
11:37:17.138 [27395] <2> getPatchLevel: got client socket
11:37:17.230 [27395] <2> getPatchLevel: file num = 4, file size = 112
11:37:17.230 [27395] <2> bpcr_read_rqst: completed send of file number 4
11:37:17.230 [27395] <2> bpcr_read_rqst: completed send of transfer_count = 16383
11:37:17.230 [27395] <2> bpcr_read_rqst: Before readclientstatus on socket 7
11:37:17.358 [27395] <2> bpcr_read_rqst: Num bytes to be sent by client = 112
11:37:17.450 [27395] <2> getPatchLevel: total chars read = 112
11:37:17.450 [27395] <2> getPatchLevel: level = >6.0MP4<
11:37:17.904 [27395] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2034: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006
11:37:17.904 [27395] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2048: service: bpcd
11:37:18.062 [27395] <2> logconnections: BPCD CONNECT FROM 10.87.0.198.58896 TO 10.67.99.227.13724
11:37:18.063 [27395] <2> vnet_connect_to_vnetd_extra: vnet_vnetd.c.178: msg: VNETD CONNECT FROM 10.87.0.198.58897 TO 10.67.99.227.13724 fd = 7
11:37:18.080 [27395] <2> vnet_vnetd_connect_forward_socket_end: vnet_vnetd.c.529: VN_REQUEST_CONNECT_FORWARD_SOCKET: 10 0x0000000a
11:37:18.246 [27395] <2> vnet_vnetd_connect_forward_socket_end: vnet_vnetd.c.546: ipc_string: 4156
11:37:18.451 [27395] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:37:18.452 [27395] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:37:18.473 [27395] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:37:18.473 [27395] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:37:18.640 [27395] <2> bpcr_get_version_rqst: bpcd version: 06000404
11:37:18.740 [27395] <2> bpcr_get_platform_rqst: Server client platform length = 2
11:37:18.740 [27395] <2> bpcr_check_for_use_ofb_support: bpcd platform nt
 

bpcm log on media server:

11:37:13.093 [27393] <2> bpcd main: offset to GMT -28800
11:37:13.093 [27393] <2> logconnections: BPCD ACCEPT FROM 10.87.0.198.58861 TO 10.87.0.198.13724
11:37:13.093 [27393] <2> bpcd main: setup_sockopts complete
11:37:13.102 [27393] <2> bpcd peer_hostname: Connection from host bckserver01 (10.87.0.198) port 58861
11:37:13.111 [27393] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:37:13.111 [27393] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:37:13.137 [27393] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:37:13.138 [27393] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:37:13.139 [27393] <2> bpcd valid_server: comparing bckserver01 and bckserver01
11:37:13.139 [27393] <4> bpcd valid_server: hostname comparison succeeded
11:37:13.140 [27393] <2> bpcd main: output socket port number = 1
11:37:13.194 [27393] <2> bpcd main: Duplicated vnetd socket on stderr
11:37:13.194 [27393] <2> bpcd main: <---- NetBackup 6.0 0 ------------initiated
11:37:13.247 [27393] <2> bpcd main: Not using VxSS authentication with bckserver01
11:37:13.293 [27393] <2> bpcd main: BPCD_FORK_CMD_RQST
11:37:13.340 [27393] <2> bpcd main: fork cmd = /usr/openv/netbackup/bin/bpbrm bpbrm -backup -S bckserver01 -c 10.67.99.227 -ct 13 -ru root -cl File_10.67.99.227 -sched Full -bt 1304393831 -dt 0 -st 0 -b 10.67.99.227_1304393831 -mediasvr bckserver01 -jobid 147385 -jobgrpid 147385 -masterversion 600000 -maxfrag 1048576 -reqid -1303437597 -mt 2 -to 0 -stunit bckserver01-hcart2-robot-tld-0 -cj 2 -rl 1 -rp 1209600 -D 14 -rt 8 -rn 0 -pool NetBackup -use_ofb -use_otm -WOFB_enabled 1 -nbu -jm -secure 1 -rg root -fso
11:37:13.340 [27393] <2> bpcd main: filter type = -1
11:37:13.340 [27393] <2> bpcd main: backuptime before fork = 0
11:37:13.341 [27393] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
11:37:13.341 [27395] <2> bpcd main: Converting args string into an argv
11:37:13.341 [27395] <2> bpcd main: Done converting args string into an argv
11:37:13.341 [27395] <2> bpcd main: child_args[0] = /usr/openv/netbackup/bin/bpbrm
11:37:13.341 [27395] <2> bpcd main: child_args[1] = bpbrm
11:37:13.341 [27395] <2> bpcd main: child_args[2] = -backup
11:37:13.341 [27395] <2> bpcd main: child_args[3] = -S
11:37:13.341 [27395] <2> bpcd main: child_args[4] = bckserver01
11:37:13.341 [27395] <2> bpcd main: child_args[5] = -c
11:37:13.341 [27395] <2> bpcd main: child_args[6] = 10.67.99.227
11:37:13.341 [27395] <2> bpcd main: child_args[7] = -ct
11:37:13.341 [27395] <2> bpcd main: child_args[8] = 13
11:37:13.342 [27395] <2> bpcd main: child_args[9] = -ru
11:37:13.342 [27395] <2> bpcd main: child_args[10] = root
11:37:13.342 [27395] <2> bpcd main: child_args[11] = -cl
11:37:13.342 [27395] <2> bpcd main: child_args[12] = File_10.67.99.227
11:37:13.342 [27395] <2> bpcd main: child_args[13] = -sched
11:37:13.342 [27395] <2> bpcd main: child_args[14] = Full
11:37:13.342 [27395] <2> bpcd main: child_args[15] = -bt
11:37:13.342 [27395] <2> bpcd main: child_args[16] = 1304393831
11:37:13.342 [27395] <2> bpcd main: child_args[17] = -dt
11:37:13.342 [27395] <2> bpcd main: child_args[18] = 0
11:37:13.342 [27395] <2> bpcd main: child_args[19] = -st
11:37:13.342 [27395] <2> bpcd main: child_args[20] = 0
11:37:13.342 [27395] <2> bpcd main: child_args[21] = -b
11:37:13.342 [27395] <2> bpcd main: child_args[22] = 10.67.99.227_1304393831
11:37:13.342 [27395] <2> bpcd main: child_args[23] = -mediasvr
11:37:13.342 [27395] <2> bpcd main: child_args[24] = bckserver01
11:37:13.342 [27395] <2> bpcd main: child_args[25] = -jobid
11:37:13.342 [27395] <2> bpcd main: child_args[26] = 147385
11:37:13.342 [27395] <2> bpcd main: child_args[27] = -jobgrpid
11:37:13.342 [27395] <2> bpcd main: child_args[28] = 147385
11:37:13.342 [27395] <2> bpcd main: child_args[29] = -masterversion
11:37:13.342 [27395] <2> bpcd main: child_args[30] = 600000
11:37:13.342 [27395] <2> bpcd main: child_args[31] = -maxfrag
11:37:13.342 [27395] <2> bpcd main: child_args[32] = 1048576
11:37:13.342 [27395] <2> bpcd main: child_args[33] = -reqid
11:37:13.342 [27395] <2> bpcd main: child_args[34] = -1303437597
11:37:13.342 [27395] <2> bpcd main: child_args[35] = -mt
11:37:13.342 [27395] <2> bpcd main: child_args[36] = 2
11:37:13.342 [27395] <2> bpcd main: child_args[37] = -to
11:37:13.342 [27395] <2> bpcd main: child_args[38] = 0
11:37:13.342 [27395] <2> bpcd main: child_args[39] = -stunit
11:37:13.342 [27395] <2> bpcd main: child_args[40] = bckserver01-hcart2-robot-tld-0
11:37:13.342 [27395] <2> bpcd main: child_args[41] = -cj
11:37:13.342 [27395] <2> bpcd main: child_args[42] = 2
11:37:13.342 [27395] <2> bpcd main: child_args[43] = -rl
11:37:13.342 [27395] <2> bpcd main: child_args[44] = 1
11:37:13.342 [27395] <2> bpcd main: child_args[45] = -rp
11:37:13.342 [27395] <2> bpcd main: child_args[46] = 1209600
11:37:13.342 [27395] <2> bpcd main: child_args[47] = -D
11:37:13.342 [27395] <2> bpcd main: child_args[48] = 14
11:37:13.342 [27395] <2> bpcd main: child_args[49] = -rt
11:37:13.342 [27395] <2> bpcd main: child_args[50] = 8
11:37:13.342 [27395] <2> bpcd main: child_args[51] = -rn
11:37:13.342 [27395] <2> bpcd main: child_args[52] = 0
11:37:13.342 [27395] <2> bpcd main: child_args[53] = -pool
11:37:13.342 [27395] <2> bpcd main: child_args[54] = NetBackup
11:37:13.342 [27395] <2> bpcd main: child_args[55] = -use_ofb
11:37:13.342 [27395] <2> bpcd main: child_args[56] = -use_otm
11:37:13.342 [27395] <2> bpcd main: child_args[57] = -WOFB_enabled
11:37:13.342 [27395] <2> bpcd main: child_args[58] = 1
11:37:13.342 [27395] <2> bpcd main: child_args[59] = -nbu
11:37:13.342 [27395] <2> bpcd main: child_args[60] = -jm
11:37:13.342 [27395] <2> bpcd main: child_args[61] = -secure
11:37:13.342 [27395] <2> bpcd main: child_args[62] = 1
11:37:13.342 [27395] <2> bpcd main: child_args[63] = -rg
11:37:13.342 [27395] <2> bpcd main: child_args[64] = root
11:37:13.342 [27395] <2> bpcd main: child_args[65] = -fso
11:37:13.342 [27395] <2> bpcd main: Before execvp of command
11:37:40.073 [27409] <2> bpcd main: offset to GMT -28800
11:37:40.074 [27409] <2> logconnections: BPCD ACCEPT FROM 10.87.0.198.58954 TO 10.87.0.198.13724
11:37:40.074 [27409] <2> bpcd main: setup_sockopts complete
11:37:40.083 [27409] <2> bpcd peer_hostname: Connection from host bckserver01 (10.87.0.198) port 58954
11:37:40.092 [27409] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:37:40.092 [27409] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:37:40.121 [27409] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:37:40.121 [27409] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:37:40.122 [27409] <2> bpcd valid_server: comparing bckserver01 and bckserver01
11:37:40.122 [27409] <4> bpcd valid_server: hostname comparison succeeded
11:37:40.123 [27409] <2> bpcd main: output socket port number = 1
11:37:40.127 [27409] <2> bpcd main: Duplicated vnetd socket on stderr
11:37:40.127 [27409] <2> bpcd main: <---- NetBackup 6.0 0 ------------initiated
11:37:40.170 [27409] <2> bpcd main: Not using VxSS authentication with bckserver01
11:37:40.220 [27409] <2> bpcd main: BPCD_GET_PROCESS_STATUS_RQST
11:37:40.270 [27409] <2> find_processes: total malloc-ed = 4096
11:37:40.282 [27409] <2> get_unxwre_processes: 119 files considered, 117 files opened/read
11:37:40.282 [27409] <2> find_processes: progs_list_size = 4096 == total calculated size = 4096 == (total_space_used = 4031 + whats_left = 65)
11:37:40.282 [27409] <2> find_processes: realloc down to 4031
11:37:40.330 [27409] <2> bpcd main: BPCD_DISCONNECT_RQST
11:37:40.330 [27409] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
11:38:41.744 [27432] <2> bpcd main: offset to GMT -28800
11:38:41.744 [27432] <2> logconnections: BPCD ACCEPT FROM 10.87.0.198.59031 TO 10.87.0.198.13724
11:38:41.744 [27432] <2> bpcd main: setup_sockopts complete
11:38:41.753 [27432] <2> bpcd peer_hostname: Connection from host bckserver01 (10.87.0.198) port 59031
11:38:41.762 [27432] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:38:41.762 [27432] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:38:41.791 [27432] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:38:41.791 [27432] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:38:41.792 [27432] <2> bpcd valid_server: comparing bckserver01 and bckserver01
11:38:41.793 [27432] <4> bpcd valid_server: hostname comparison succeeded
11:38:41.793 [27432] <2> bpcd main: output socket port number = 1
11:38:41.795 [27432] <2> bpcd main: Duplicated vnetd socket on stderr
11:38:41.795 [27432] <2> bpcd main: <---- NetBackup 6.0 0 ------------initiated
11:38:41.841 [27432] <2> bpcd main: Not using VxSS authentication with bckserver01
11:38:41.890 [27432] <2> bpcd main: BPCD_GET_PROCESS_STATUS_RQST
11:38:41.940 [27432] <2> find_processes: total malloc-ed = 4096
11:38:41.953 [27432] <2> get_unxwre_processes: 118 files considered, 116 files opened/read
11:38:41.953 [27432] <2> find_processes: progs_list_size = 4096 == total calculated size = 4096 == (total_space_used = 3971 + whats_left = 125)
11:38:41.953 [27432] <2> find_processes: realloc down to 3971
11:38:42.003 [27432] <2> bpcd main: BPCD_DISCONNECT_RQST
11:38:42.003 [27432] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
11:39:38.884 [27453] <2> bpcd main: offset to GMT -28800
11:39:38.884 [27453] <2> logconnections: BPCD ACCEPT FROM 10.87.0.198.59098 TO 10.87.0.198.13724
11:39:38.884 [27453] <2> bpcd main: setup_sockopts complete
11:39:38.893 [27453] <2> bpcd peer_hostname: Connection from host bckserver01 (10.87.0.198) port 59098
11:39:38.901 [27453] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:39:38.901 [27453] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:39:38.927 [27453] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:39:38.927 [27453] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:39:38.929 [27453] <2> bpcd valid_server: comparing bckserver01 and bckserver01
11:39:38.929 [27453] <4> bpcd valid_server: hostname comparison succeeded
11:39:38.930 [27453] <2> bpcd main: output socket port number = 1
11:39:38.931 [27453] <2> bpcd main: Duplicated vnetd socket on stderr
11:39:38.931 [27453] <2> bpcd main: <---- NetBackup 6.0 0 ------------initiated
11:39:38.971 [27453] <2> bpcd main: Not using VxSS authentication with bckserver01
11:39:39.021 [27453] <2> bpcd main: BPCD_IMMED_CMD_RQST
11:39:39.021 [27453] <2> bpcd main: immed cmd = /usr/openv/netbackup/bin/bptm -delete_expired 1>/dev/null 2>/dev/null
11:39:39.021 [27453] <2> sanitary_mb_str: detected /dev/null after redirection
11:39:39.021 [27453] <2> sanitary_mb_str: detected /dev/null after redirection
11:39:39.021 [27453] <2> sanitary_mb_str: String "/usr/openv/netbackup/bin/bptm -delete_expired 1>/dev/null 2>/dev/null" is considered sanitary.
11:39:39.272 [27453] <2> bpcd main: completed command
11:39:39.273 [27453] <2> bpcd main: immed cmd status = 0x0
11:39:39.273 [27453] <2> bpcd main: immed cmd status = 0
11:39:39.273 [27453] <2> bpcd main: BPCD_DISCONNECT_RQST
11:39:39.273 [27453] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
11:39:39.371 [27457] <2> bpcd main: offset to GMT -28800
11:39:39.371 [27457] <2> logconnections: BPCD ACCEPT FROM 10.87.0.198.59107 TO 10.87.0.198.13724
11:39:39.371 [27457] <2> bpcd main: setup_sockopts complete
11:39:39.380 [27457] <2> bpcd peer_hostname: Connection from host bckserver01 (10.87.0.198) port 59107
11:39:39.388 [27457] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:39:39.388 [27457] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:39:39.416 [27457] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:39:39.416 [27457] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:39:39.417 [27457] <2> bpcd valid_server: comparing bckserver01 and bckserver01
11:39:39.418 [27457] <4> bpcd valid_server: hostname comparison succeeded
11:39:39.418 [27457] <2> bpcd main: output socket port number = 1
11:39:39.420 [27457] <2> bpcd main: Duplicated vnetd socket on stderr
11:39:39.420 [27457] <2> bpcd main: <---- NetBackup 6.0 0 ------------initiated
11:39:39.461 [27457] <2> bpcd main: Not using VxSS authentication with bckserver01
11:39:39.511 [27457] <2> bpcd main: BPCD_IMMED_CMD_RQST
11:39:39.511 [27457] <2> bpcd main: immed cmd = /usr/openv/netbackup/bin/bptm -delete_all_expired 1>/dev/null 2>/dev/null
11:39:39.511 [27457] <2> sanitary_mb_str: detected /dev/null after redirection
11:39:39.511 [27457] <2> sanitary_mb_str: detected /dev/null after redirection
11:39:39.511 [27457] <2> sanitary_mb_str: String "/usr/openv/netbackup/bin/bptm -delete_all_expired 1>/dev/null 2>/dev/null" is considered sanitary.
11:39:39.951 [27457] <2> bpcd main: completed command
11:39:39.951 [27457] <2> bpcd main: immed cmd status = 0x0
11:39:39.951 [27457] <2> bpcd main: immed cmd status = 0
11:39:39.952 [27457] <2> bpcd main: BPCD_DISCONNECT_RQST
11:39:39.952 [27457] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
11:39:43.424 [27464] <2> bpcd main: offset to GMT -28800
11:39:43.424 [27464] <2> logconnections: BPCD ACCEPT FROM 10.87.0.198.59126 TO 10.87.0.198.13724
11:39:43.424 [27464] <2> bpcd main: setup_sockopts complete
11:39:43.434 [27464] <2> bpcd peer_hostname: Connection from host bckserver01 (10.87.0.198) port 59126
11:39:43.442 [27464] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:39:43.442 [27464] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:39:43.471 [27464] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:39:43.471 [27464] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:39:43.473 [27464] <2> bpcd valid_server: comparing bckserver01 and bckserver01
11:39:43.473 [27464] <4> bpcd valid_server: hostname comparison succeeded
11:39:43.473 [27464] <2> bpcd main: output socket port number = 1
11:39:43.475 [27464] <2> bpcd main: Duplicated vnetd socket on stderr
11:39:43.475 [27464] <2> bpcd main: <---- NetBackup 6.0 0 ------------initiated
11:39:43.521 [27464] <2> bpcd main: Not using VxSS authentication with bckserver01
11:39:43.571 [27464] <2> bpcd main: BPCD_GET_PROCESS_STATUS_RQST
11:39:43.621 [27464] <2> find_processes: total malloc-ed = 4096
11:39:43.634 [27464] <2> get_unxwre_processes: 118 files considered, 116 files opened/read
11:39:43.634 [27464] <2> find_processes: progs_list_size = 4096 == total calculated size = 4096 == (total_space_used = 3971 + whats_left = 125)
11:39:43.634 [27464] <2> find_processes: realloc down to 3971
11:39:43.681 [27464] <2> bpcd main: BPCD_DISCONNECT_RQST
11:39:43.681 [27464] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
11:40:45.096 [27487] <2> bpcd main: offset to GMT -28800
11:40:45.096 [27487] <2> logconnections: BPCD ACCEPT FROM 10.87.0.198.59203 TO 10.87.0.198.13724
11:40:45.096 [27487] <2> bpcd main: setup_sockopts complete
11:40:45.106 [27487] <2> bpcd peer_hostname: Connection from host bckserver01 (10.87.0.198) port 59203
11:40:45.114 [27487] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:40:45.114 [27487] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:40:45.143 [27487] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:40:45.143 [27487] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:40:45.144 [27487] <2> bpcd valid_server: comparing bckserver01 and bckserver01
11:40:45.144 [27487] <4> bpcd valid_server: hostname comparison succeeded
11:40:45.145 [27487] <2> bpcd main: output socket port number = 1
11:40:45.149 [27487] <2> bpcd main: Duplicated vnetd socket on stderr
11:40:45.149 [27487] <2> bpcd main: <---- NetBackup 6.0 0 ------------initiated
11:40:45.192 [27487] <2> bpcd main: Not using VxSS authentication with bckserver01
11:40:45.242 [27487] <2> bpcd main: BPCD_GET_PROCESS_STATUS_RQST
11:40:45.291 [27487] <2> find_processes: total malloc-ed = 4096
11:40:45.305 [27487] <2> get_unxwre_processes: 118 files considered, 116 files opened/read
11:40:45.306 [27487] <2> find_processes: progs_list_size = 4096 == total calculated size = 4096 == (total_space_used = 3971 + whats_left = 125)
11:40:45.306 [27487] <2> find_processes: realloc down to 3971
11:40:45.352 [27487] <2> bpcd main: BPCD_DISCONNECT_RQST
11:40:45.352 [27487] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
11:41:46.755 [27513] <2> bpcd main: offset to GMT -28800
11:41:46.756 [27513] <2> logconnections: BPCD ACCEPT FROM 10.87.0.198.59287 TO 10.87.0.198.13724
11:41:46.756 [27513] <2> bpcd main: setup_sockopts complete
11:41:46.765 [27513] <2> bpcd peer_hostname: Connection from host bckserver01 (10.87.0.198) port 59287
11:41:46.774 [27513] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:41:46.774 [27513] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:41:46.801 [27513] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:41:46.801 [27513] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:41:46.802 [27513] <2> bpcd valid_server: comparing bckserver01 and bckserver01
11:41:46.803 [27513] <4> bpcd valid_server: hostname comparison succeeded
11:41:46.803 [27513] <2> bpcd main: output socket port number = 1
11:41:46.807 [27513] <2> bpcd main: Duplicated vnetd socket on stderr
11:41:46.807 [27513] <2> bpcd main: <---- NetBackup 6.0 0 ------------initiated
11:41:46.852 [27513] <2> bpcd main: Not using VxSS authentication with bckserver01
11:41:46.902 [27513] <2> bpcd main: BPCD_GET_PROCESS_STATUS_RQST
11:41:46.952 [27513] <2> find_processes: total malloc-ed = 4096
11:41:46.965 [27513] <2> get_unxwre_processes: 118 files considered, 116 files opened/read
11:41:46.965 [27513] <2> find_processes: progs_list_size = 4096 == total calculated size = 4096 == (total_space_used = 3971 + whats_left = 125)
11:41:46.965 [27513] <2> find_processes: realloc down to 3971
11:41:47.013 [27513] <2> bpcd main: BPCD_DISCONNECT_RQST
11:41:47.013 [27513] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
11:42:48.426 [27537] <2> bpcd main: offset to GMT -28800
11:42:48.426 [27537] <2> logconnections: BPCD ACCEPT FROM 10.87.0.198.59364 TO 10.87.0.198.13724
11:42:48.426 [27537] <2> bpcd main: setup_sockopts complete
11:42:48.435 [27537] <2> bpcd peer_hostname: Connection from host bckserver01 (10.87.0.198) port 59364
11:42:48.444 [27537] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:42:48.444 [27537] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:42:48.472 [27537] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:42:48.473 [27537] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:42:48.474 [27537] <2> bpcd valid_server: comparing bckserver01 and bckserver01
11:42:48.474 [27537] <4> bpcd valid_server: hostname comparison succeeded
11:42:48.475 [27537] <2> bpcd main: output socket port number = 1
11:42:48.476 [27537] <2> bpcd main: Duplicated vnetd socket on stderr
11:42:48.476 [27537] <2> bpcd main: <---- NetBackup 6.0 0 ------------initiated
11:42:48.523 [27537] <2> bpcd main: Not using VxSS authentication with bckserver01
11:42:48.573 [27537] <2> bpcd main: BPCD_GET_PROCESS_STATUS_RQST
11:42:48.623 [27537] <2> find_processes: total malloc-ed = 4096
11:42:48.637 [27537] <2> get_unxwre_processes: 118 files considered, 116 files opened/read
11:42:48.637 [27537] <2> find_processes: progs_list_size = 4096 == total calculated size = 4096 == (total_space_used = 3971 + whats_left = 125)
11:42:48.637 [27537] <2> find_processes: realloc down to 3971
11:42:48.683 [27537] <2> bpcd main: BPCD_DISCONNECT_RQST
11:42:48.683 [27537] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
11:43:50.097 [27560] <2> bpcd main: offset to GMT -28800
11:43:50.097 [27560] <2> logconnections: BPCD ACCEPT FROM 10.87.0.198.59442 TO 10.87.0.198.13724
11:43:50.097 [27560] <2> bpcd main: setup_sockopts complete
11:43:50.106 [27560] <2> bpcd peer_hostname: Connection from host bckserver01 (10.87.0.198) port 59442
11:43:50.114 [27560] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:43:50.115 [27560] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:43:50.142 [27560] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:43:50.142 [27560] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:43:50.144 [27560] <2> bpcd valid_server: comparing bckserver01 and bckserver01
11:43:50.144 [27560] <4> bpcd valid_server: hostname comparison succeeded
11:43:50.145 [27560] <2> bpcd main: output socket port number = 1
11:43:50.146 [27560] <2> bpcd main: Duplicated vnetd socket on stderr
11:43:50.146 [27560] <2> bpcd main: <---- NetBackup 6.0 0 ------------initiated
11:43:50.193 [27560] <2> bpcd main: Not using VxSS authentication with bckserver01
11:43:50.243 [27560] <2> bpcd main: BPCD_GET_PROCESS_STATUS_RQST
11:43:50.293 [27560] <2> find_processes: total malloc-ed = 4096
11:43:50.306 [27560] <2> get_unxwre_processes: 118 files considered, 116 files opened/read
11:43:50.306 [27560] <2> find_processes: progs_list_size = 4096 == total calculated size = 4096 == (total_space_used = 3971 + whats_left = 125)
11:43:50.306 [27560] <2> find_processes: realloc down to 3971
11:43:50.354 [27560] <2> bpcd main: BPCD_DISCONNECT_RQST
11:43:50.354 [27560] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
11:44:51.764 [27583] <2> bpcd main: offset to GMT -28800
11:44:51.764 [27583] <2> logconnections: BPCD ACCEPT FROM 10.87.0.198.59519 TO 10.87.0.198.13724
11:44:51.764 [27583] <2> bpcd main: setup_sockopts complete
11:44:51.773 [27583] <2> bpcd peer_hostname: Connection from host bckserver01 (10.87.0.198) port 59519
11:44:51.782 [27583] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:44:51.782 [27583] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:44:51.811 [27583] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:44:51.811 [27583] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:44:51.812 [27583] <2> bpcd valid_server: comparing bckserver01 and bckserver01
11:44:51.813 [27583] <4> bpcd valid_server: hostname comparison succeeded
11:44:51.813 [27583] <2> bpcd main: output socket port number = 1
11:44:51.815 [27583] <2> bpcd main: Duplicated vnetd socket on stderr
11:44:51.815 [27583] <2> bpcd main: <---- NetBackup 6.0 0 ------------initiated
11:44:51.864 [27583] <2> bpcd main: Not using VxSS authentication with bckserver01
11:44:51.914 [27583] <2> bpcd main: BPCD_GET_PROCESS_STATUS_RQST
11:44:51.964 [27583] <2> find_processes: total malloc-ed = 4096
11:44:51.976 [27583] <2> get_unxwre_processes: 118 files considered, 116 files opened/read
11:44:51.976 [27583] <2> find_processes: progs_list_size = 4096 == total calculated size = 4096 == (total_space_used = 3970 + whats_left = 126)
11:44:51.976 [27583] <2> find_processes: realloc down to 3970
11:44:52.024 [27583] <2> bpcd main: BPCD_DISCONNECT_RQST
11:44:52.024 [27583] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
11:45:53.434 [27607] <2> bpcd main: offset to GMT -28800
11:45:53.434 [27607] <2> logconnections: BPCD ACCEPT FROM 10.87.0.198.59597 TO 10.87.0.198.13724
11:45:53.434 [27607] <2> bpcd main: setup_sockopts complete
11:45:53.443 [27607] <2> bpcd peer_hostname: Connection from host bckserver01 (10.87.0.198) port 59597
11:45:53.451 [27607] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:45:53.451 [27607] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:45:53.477 [27607] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:45:53.477 [27607] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:45:53.478 [27607] <2> bpcd valid_server: comparing bckserver01 and bckserver01
11:45:53.478 [27607] <4> bpcd valid_server: hostname comparison succeeded
11:45:53.479 [27607] <2> bpcd main: output socket port number = 1
11:45:53.480 [27607] <2> bpcd main: Duplicated vnetd socket on stderr
11:45:53.480 [27607] <2> bpcd main: <---- NetBackup 6.0 0 ------------initiated
11:45:53.525 [27607] <2> bpcd main: Not using VxSS authentication with bckserver01
11:45:53.575 [27607] <2> bpcd main: BPCD_GET_PROCESS_STATUS_RQST
11:45:53.624 [27607] <2> find_processes: total malloc-ed = 4096
11:45:53.638 [27607] <2> get_unxwre_processes: 118 files considered, 116 files opened/read
11:45:53.639 [27607] <2> find_processes: progs_list_size = 4096 == total calculated size = 4096 == (total_space_used = 3971 + whats_left = 125)
11:45:53.639 [27607] <2> find_processes: realloc down to 3971
11:45:53.685 [27607] <2> bpcd main: BPCD_DISCONNECT_RQST
11:45:53.685 [27607] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
11:46:09.714 [27626] <2> bpcd main: offset to GMT -28800
11:46:09.714 [27626] <2> logconnections: BPCD ACCEPT FROM 10.87.0.198.59622 TO 10.87.0.198.13724
11:46:09.714 [27626] <2> bpcd main: setup_sockopts complete
11:46:09.723 [27626] <2> bpcd peer_hostname: Connection from host bckserver01 (10.87.0.198) port 59622
11:46:09.732 [27626] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:46:09.732 [27626] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:46:09.760 [27626] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:46:09.760 [27626] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:46:09.761 [27626] <2> bpcd valid_server: comparing bckserver01 and bckserver01
11:46:09.762 [27626] <4> bpcd valid_server: hostname comparison succeeded
11:46:09.762 [27626] <2> bpcd main: output socket port number = 1
11:46:09.766 [27626] <2> bpcd main: Duplicated vnetd socket on stderr
11:46:09.766 [27626] <2> bpcd main: <---- NetBackup 6.0 0 ------------initiated
11:46:09.824 [27626] <2> bpcd main: Not using VxSS authentication with bckserver01
11:46:09.865 [27626] <2> bpcd main: BPCD_FORK_CMD_RQST
11:46:09.865 [27626] <2> bpcd main: fork cmd = /usr/openv/netbackup/bin/bptm bptm -rptdrv -jobid -1303437598 -jm
11:46:09.865 [27626] <2> bpcd main: filter type = -1
11:46:09.865 [27626] <2> bpcd main: backuptime before fork = 0
11:46:09.867 [27629] <2> bpcd main: Converting args string into an argv
11:46:09.867 [27629] <2> bpcd main: Done converting args string into an argv
11:46:09.867 [27629] <2> bpcd main: child_args[0] = /usr/openv/netbackup/bin/bptm
11:46:09.867 [27629] <2> bpcd main: child_args[1] = bptm
11:46:09.867 [27629] <2> bpcd main: child_args[2] = -rptdrv
11:46:09.867 [27629] <2> bpcd main: child_args[3] = -jobid
11:46:09.867 [27629] <2> bpcd main: child_args[4] = -1303437598
11:46:09.867 [27629] <2> bpcd main: child_args[5] = -jm
11:46:09.867 [27629] <2> bpcd main: Before execvp of command
11:46:09.870 [27626] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
11:46:55.098 [27640] <2> bpcd main: offset to GMT -28800
11:46:55.098 [27640] <2> logconnections: BPCD ACCEPT FROM 10.87.0.198.59708 TO 10.87.0.198.13724
11:46:55.099 [27640] <2> bpcd main: setup_sockopts complete
11:46:55.108 [27640] <2> bpcd peer_hostname: Connection from host bckserver01 (10.87.0.198) port 59708
11:46:55.117 [27640] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:46:55.117 [27640] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:46:55.146 [27640] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:46:55.146 [27640] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:46:55.147 [27640] <2> bpcd valid_server: comparing bckserver01 and bckserver01
11:46:55.148 [27640] <4> bpcd valid_server: hostname comparison succeeded
11:46:55.148 [27640] <2> bpcd main: output socket port number = 1
11:46:55.152 [27640] <2> bpcd main: Duplicated vnetd socket on stderr
11:46:55.152 [27640] <2> bpcd main: <---- NetBackup 6.0 0 ------------initiated
11:46:55.195 [27640] <2> bpcd main: Not using VxSS authentication with bckserver01
11:46:55.245 [27640] <2> bpcd main: BPCD_GET_PROCESS_STATUS_RQST
11:46:55.295 [27640] <2> find_processes: total malloc-ed = 4096
11:46:55.310 [27640] <2> get_unxwre_processes: 118 files considered, 116 files opened/read
11:46:55.310 [27640] <2> find_processes: progs_list_size = 4096 == total calculated size = 4096 == (total_space_used = 3970 + whats_left = 126)
11:46:55.310 [27640] <2> find_processes: realloc down to 3970
11:46:55.355 [27640] <2> bpcd main: BPCD_DISCONNECT_RQST
11:46:55.355 [27640] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
11:47:56.765 [27663] <2> bpcd main: offset to GMT -28800
11:47:56.765 [27663] <2> logconnections: BPCD ACCEPT FROM 10.87.0.198.59786 TO 10.87.0.198.13724
11:47:56.766 [27663] <2> bpcd main: setup_sockopts complete
11:47:56.774 [27663] <2> bpcd peer_hostname: Connection from host bckserver01 (10.87.0.198) port 59786
11:47:56.783 [27663] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:47:56.783 [27663] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:47:56.811 [27663] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:47:56.811 [27663] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:47:56.813 [27663] <2> bpcd valid_server: comparing bckserver01 and bckserver01
11:47:56.813 [27663] <4> bpcd valid_server: hostname comparison succeeded
11:47:56.813 [27663] <2> bpcd main: output socket port number = 1
11:47:56.815 [27663] <2> bpcd main: Duplicated vnetd socket on stderr
11:47:56.815 [27663] <2> bpcd main: <---- NetBackup 6.0 0 ------------initiated
11:47:56.856 [27663] <2> bpcd main: Not using VxSS authentication with bckserver01
11:47:56.906 [27663] <2> bpcd main: BPCD_GET_PROCESS_STATUS_RQST
11:47:56.956 [27663] <2> find_processes: total malloc-ed = 4096
11:47:56.969 [27663] <2> get_unxwre_processes: 118 files considered, 116 files opened/read
11:47:56.970 [27663] <2> find_processes: progs_list_size = 4096 == total calculated size = 4096 == (total_space_used = 3970 + whats_left = 126)
11:47:56.970 [27663] <2> find_processes: realloc down to 3970
11:47:57.016 [27663] <2> bpcd main: BPCD_DISCONNECT_RQST
11:47:57.016 [27663] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
11:48:58.425 [27687] <2> bpcd main: offset to GMT -28800
11:48:58.425 [27687] <2> logconnections: BPCD ACCEPT FROM 10.87.0.198.59863 TO 10.87.0.198.13724
11:48:58.425 [27687] <2> bpcd main: setup_sockopts complete
11:48:58.434 [27687] <2> bpcd peer_hostname: Connection from host bckserver01 (10.87.0.198) port 59863
11:48:58.443 [27687] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:48:58.444 [27687] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:48:58.473 [27687] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:48:58.473 [27687] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:48:58.474 [27687] <2> bpcd valid_server: comparing bckserver01 and bckserver01
11:48:58.475 [27687] <4> bpcd valid_server: hostname comparison succeeded
11:48:58.475 [27687] <2> bpcd main: output socket port number = 1
11:48:58.479 [27687] <2> bpcd main: Duplicated vnetd socket on stderr
11:48:58.479 [27687] <2> bpcd main: <---- NetBackup 6.0 0 ------------initiated
11:48:58.516 [27687] <2> bpcd main: Not using VxSS authentication with bckserver01
11:48:58.566 [27687] <2> bpcd main: BPCD_GET_PROCESS_STATUS_RQST
11:48:58.616 [27687] <2> find_processes: total malloc-ed = 4096
11:48:58.629 [27687] <2> get_unxwre_processes: 118 files considered, 116 files opened/read
11:48:58.629 [27687] <2> find_processes: progs_list_size = 4096 == total calculated size = 4096 == (total_space_used = 3970 + whats_left = 126)
11:48:58.629 [27687] <2> find_processes: realloc down to 3970
11:48:58.676 [27687] <2> bpcd main: BPCD_DISCONNECT_RQST
11:48:58.677 [27687] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
11:49:40.286 [27707] <2> bpcd main: offset to GMT -28800
11:49:40.286 [27707] <2> logconnections: BPCD ACCEPT FROM 10.87.0.198.59916 TO 10.87.0.198.13724
11:49:40.287 [27707] <2> bpcd main: setup_sockopts complete
11:49:40.296 [27707] <2> bpcd peer_hostname: Connection from host bckserver01 (10.87.0.198) port 59916
11:49:40.304 [27707] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:49:40.304 [27707] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:49:40.333 [27707] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:49:40.333 [27707] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:49:40.334 [27707] <2> bpcd valid_server: comparing bckserver01 and bckserver01
11:49:40.335 [27707] <4> bpcd valid_server: hostname comparison succeeded
11:49:40.335 [27707] <2> bpcd main: output socket port number = 1
11:49:40.337 [27707] <2> bpcd main: Duplicated vnetd socket on stderr
11:49:40.337 [27707] <2> bpcd main: <---- NetBackup 6.0 0 ------------initiated
11:49:40.377 [27707] <2> bpcd main: Not using VxSS authentication with bckserver01
11:49:40.427 [27707] <2> bpcd main: BPCD_IMMED_CMD_RQST
11:49:40.427 [27707] <2> bpcd main: immed cmd = /usr/openv/netbackup/bin/bptm -delete_expired 1>/dev/null 2>/dev/null
11:49:40.427 [27707] <2> sanitary_mb_str: detected /dev/null after redirection
11:49:40.427 [27707] <2> sanitary_mb_str: detected /dev/null after redirection
11:49:40.427 [27707] <2> sanitary_mb_str: String "/usr/openv/netbackup/bin/bptm -delete_expired 1>/dev/null 2>/dev/null" is considered sanitary.
11:49:40.684 [27707] <2> bpcd main: completed command
11:49:40.684 [27707] <2> bpcd main: immed cmd status = 0x0
11:49:40.684 [27707] <2> bpcd main: immed cmd status = 0
11:49:40.684 [27707] <2> bpcd main: BPCD_DISCONNECT_RQST
11:49:40.684 [27707] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
11:49:40.786 [27711] <2> bpcd main: offset to GMT -28800
11:49:40.786 [27711] <2> logconnections: BPCD ACCEPT FROM 10.87.0.198.59924 TO 10.87.0.198.13724
11:49:40.786 [27711] <2> bpcd main: setup_sockopts complete
11:49:40.795 [27711] <2> bpcd peer_hostname: Connection from host bckserver01 (10.87.0.198) port 59924
11:49:40.803 [27711] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:49:40.803 [27711] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:49:40.829 [27711] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:49:40.829 [27711] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:49:40.830 [27711] <2> bpcd valid_server: comparing bckserver01 and bckserver01
11:49:40.830 [27711] <4> bpcd valid_server: hostname comparison succeeded
11:49:40.830 [27711] <2> bpcd main: output socket port number = 1
11:49:40.832 [27711] <2> bpcd main: Duplicated vnetd socket on stderr
11:49:40.832 [27711] <2> bpcd main: <---- NetBackup 6.0 0 ------------initiated
11:49:40.877 [27711] <2> bpcd main: Not using VxSS authentication with bckserver01
11:49:40.927 [27711] <2> bpcd main: BPCD_IMMED_CMD_RQST
11:49:40.927 [27711] <2> bpcd main: immed cmd = /usr/openv/netbackup/bin/bptm -delete_all_expired 1>/dev/null 2>/dev/null
11:49:40.927 [27711] <2> sanitary_mb_str: detected /dev/null after redirection
11:49:40.927 [27711] <2> sanitary_mb_str: detected /dev/null after redirection
11:49:40.927 [27711] <2> sanitary_mb_str: String "/usr/openv/netbackup/bin/bptm -delete_all_expired 1>/dev/null 2>/dev/null" is considered sanitary.
11:49:41.390 [27711] <2> bpcd main: completed command
11:49:41.390 [27711] <2> bpcd main: immed cmd status = 0x0
11:49:41.390 [27711] <2> bpcd main: immed cmd status = 0
11:49:41.390 [27711] <2> bpcd main: BPCD_DISCONNECT_RQST
11:49:41.390 [27711] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
11:50:00.082 [27718] <2> bpcd main: offset to GMT -28800
11:50:00.082 [27718] <2> logconnections: BPCD ACCEPT FROM 10.87.0.198.59958 TO 10.87.0.198.13724
11:50:00.082 [27718] <2> bpcd main: setup_sockopts complete
11:50:00.091 [27718] <2> bpcd peer_hostname: Connection from host bckserver01 (10.87.0.198) port 59958
11:50:00.100 [27718] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:50:00.100 [27718] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:50:00.128 [27718] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:50:00.128 [27718] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:50:00.129 [27718] <2> bpcd valid_server: comparing bckserver01 and bckserver01
11:50:00.130 [27718] <4> bpcd valid_server: hostname comparison succeeded
11:50:00.130 [27718] <2> bpcd main: output socket port number = 1
11:50:00.134 [27718] <2> bpcd main: Duplicated vnetd socket on stderr
11:50:00.134 [27718] <2> bpcd main: <---- NetBackup 6.0 0 ------------initiated
11:50:00.177 [27718] <2> bpcd main: Not using VxSS authentication with bckserver01
11:50:00.227 [27718] <2> bpcd main: BPCD_GET_PROCESS_STATUS_RQST
11:50:00.277 [27718] <2> find_processes: total malloc-ed = 4096
11:50:00.291 [27718] <2> get_unxwre_processes: 118 files considered, 116 files opened/read
11:50:00.291 [27718] <2> find_processes: progs_list_size = 4096 == total calculated size = 4096 == (total_space_used = 3970 + whats_left = 126)
11:50:00.291 [27718] <2> find_processes: realloc down to 3970
11:50:00.337 [27718] <2> bpcd main: BPCD_DISCONNECT_RQST
11:50:00.337 [27718] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
11:51:01.758 [27756] <2> bpcd main: offset to GMT -28800
11:51:01.759 [27756] <2> logconnections: BPCD ACCEPT FROM 10.87.0.198.60035 TO 10.87.0.198.13724
11:51:01.759 [27756] <2> bpcd main: setup_sockopts complete
11:51:01.768 [27756] <2> bpcd peer_hostname: Connection from host bckserver01 (10.87.0.198) port 60035
11:51:01.777 [27756] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:51:01.777 [27756] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:51:01.805 [27756] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:51:01.805 [27756] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:51:01.806 [27756] <2> bpcd valid_server: comparing bckserver01 and bckserver01
11:51:01.806 [27756] <4> bpcd valid_server: hostname comparison succeeded
11:51:01.807 [27756] <2> bpcd main: output socket port number = 1
11:51:01.809 [27756] <2> bpcd main: Duplicated vnetd socket on stderr
11:51:01.809 [27756] <2> bpcd main: <---- NetBackup 6.0 0 ------------initiated
11:51:01.849 [27756] <2> bpcd main: Not using VxSS authentication with bckserver01
11:51:01.899 [27756] <2> bpcd main: BPCD_GET_PROCESS_STATUS_RQST
11:51:01.949 [27756] <2> find_processes: total malloc-ed = 4096
11:51:01.961 [27756] <2> get_unxwre_processes: 118 files considered, 116 files opened/read
11:51:01.961 [27756] <2> find_processes: progs_list_size = 4096 == total calculated size = 4096 == (total_space_used = 3970 + whats_left = 126)
11:51:01.961 [27756] <2> find_processes: realloc down to 3970
11:51:02.011 [27756] <2> bpcd main: BPCD_DISCONNECT_RQST
11:51:02.011 [27756] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
11:52:03.433 [27785] <2> bpcd main: offset to GMT -28800
11:52:03.433 [27785] <2> logconnections: BPCD ACCEPT FROM 10.87.0.198.60120 TO 10.87.0.198.13724
11:52:03.433 [27785] <2> bpcd main: setup_sockopts complete
11:52:03.443 [27785] <2> bpcd peer_hostname: Connection from host bckserver01 (10.87.0.198) port 60120
11:52:03.452 [27785] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:52:03.452 [27785] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:52:03.482 [27785] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:52:03.482 [27785] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:52:03.483 [27785] <2> bpcd valid_server: comparing bckserver01 and bckserver01
11:52:03.483 [27785] <4> bpcd valid_server: hostname comparison succeeded
11:52:03.484 [27785] <2> bpcd main: output socket port number = 1
11:52:03.488 [27785] <2> bpcd main: Duplicated vnetd socket on stderr
11:52:03.488 [27785] <2> bpcd main: <---- NetBackup 6.0 0 ------------initiated
11:52:03.529 [27785] <2> bpcd main: Not using VxSS authentication with bckserver01
11:52:03.579 [27785] <2> bpcd main: BPCD_GET_PROCESS_STATUS_RQST
11:52:03.629 [27785] <2> find_processes: total malloc-ed = 4096
11:52:03.644 [27785] <2> get_unxwre_processes: 118 files considered, 116 files opened/read
11:52:03.644 [27785] <2> find_processes: progs_list_size = 4096 == total calculated size = 4096 == (total_space_used = 3970 + whats_left = 126)
11:52:03.644 [27785] <2> find_processes: realloc down to 3970
11:52:03.689 [27785] <2> bpcd main: BPCD_DISCONNECT_RQST
11:52:03.690 [27785] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
11:53:05.110 [27808] <2> bpcd main: offset to GMT -28800
11:53:05.111 [27808] <2> logconnections: BPCD ACCEPT FROM 10.87.0.198.60197 TO 10.87.0.198.13724
11:53:05.111 [27808] <2> bpcd main: setup_sockopts complete
11:53:05.119 [27808] <2> bpcd peer_hostname: Connection from host bckserver01 (10.87.0.198) port 60197
11:53:05.129 [27808] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:53:05.129 [27808] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:53:05.154 [27808] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:53:05.155 [27808] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:53:05.156 [27808] <2> bpcd valid_server: comparing bckserver01 and bckserver01
11:53:05.156 [27808] <4> bpcd valid_server: hostname comparison succeeded
11:53:05.156 [27808] <2> bpcd main: output socket port number = 1
11:53:05.158 [27808] <2> bpcd main: Duplicated vnetd socket on stderr
11:53:05.158 [27808] <2> bpcd main: <---- NetBackup 6.0 0 ------------initiated
11:53:05.200 [27808] <2> bpcd main: Not using VxSS authentication with bckserver01
11:53:05.250 [27808] <2> bpcd main: BPCD_GET_PROCESS_STATUS_RQST
11:53:05.300 [27808] <2> find_processes: total malloc-ed = 4096
11:53:05.314 [27808] <2> get_unxwre_processes: 118 files considered, 116 files opened/read
11:53:05.314 [27808] <2> find_processes: progs_list_size = 4096 == total calculated size = 4096 == (total_space_used = 3968 + whats_left = 128)
11:53:05.314 [27808] <2> find_processes: realloc down to 3968
11:53:05.360 [27808] <2> bpcd main: BPCD_DISCONNECT_RQST
11:53:05.360 [27808] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
11:54:06.770 [27832] <2> bpcd main: offset to GMT -28800
11:54:06.770 [27832] <2> logconnections: BPCD ACCEPT FROM 10.87.0.198.60275 TO 10.87.0.198.13724
11:54:06.771 [27832] <2> bpcd main: setup_sockopts complete
11:54:06.779 [27832] <2> bpcd peer_hostname: Connection from host bckserver01 (10.87.0.198) port 60275
11:54:06.788 [27832] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:54:06.789 [27832] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:54:06.814 [27832] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:54:06.814 [27832] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:54:06.816 [27832] <2> bpcd valid_server: comparing bckserver01 and bckserver01
11:54:06.816 [27832] <4> bpcd valid_server: hostname comparison succeeded
11:54:06.816 [27832] <2> bpcd main: output socket port number = 1
11:54:06.818 [27832] <2> bpcd main: Duplicated vnetd socket on stderr
11:54:06.818 [27832] <2> bpcd main: <---- NetBackup 6.0 0 ------------initiated
11:54:06.861 [27832] <2> bpcd main: Not using VxSS authentication with bckserver01
11:54:06.910 [27832] <2> bpcd main: BPCD_GET_PROCESS_STATUS_RQST
11:54:06.960 [27832] <2> find_processes: total malloc-ed = 4096
11:54:06.975 [27832] <2> get_unxwre_processes: 118 files considered, 116 files opened/read
11:54:06.975 [27832] <2> find_processes: progs_list_size = 4096 == total calculated size = 4096 == (total_space_used = 3979 + whats_left = 117)
11:54:06.975 [27832] <2> find_processes: realloc down to 3979
11:54:07.021 [27832] <2> bpcd main: BPCD_DISCONNECT_RQST
11:54:07.021 [27832] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
11:55:08.430 [27855] <2> bpcd main: offset to GMT -28800
11:55:08.431 [27855] <2> logconnections: BPCD ACCEPT FROM 10.87.0.198.60352 TO 10.87.0.198.13724
11:55:08.431 [27855] <2> bpcd main: setup_sockopts complete
11:55:08.440 [27855] <2> bpcd peer_hostname: Connection from host bckserver01 (10.87.0.198) port 60352
11:55:08.448 [27855] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:55:08.448 [27855] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:55:08.474 [27855] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:55:08.474 [27855] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:55:08.475 [27855] <2> bpcd valid_server: comparing bckserver01 and bckserver01
11:55:08.476 [27855] <4> bpcd valid_server: hostname comparison succeeded
11:55:08.476 [27855] <2> bpcd main: output socket port number = 1
11:55:08.480 [27855] <2> bpcd main: Duplicated vnetd socket on stderr
11:55:08.480 [27855] <2> bpcd main: <---- NetBackup 6.0 0 ------------initiated
11:55:08.521 [27855] <2> bpcd main: Not using VxSS authentication with bckserver01
11:55:08.571 [27855] <2> bpcd main: BPCD_GET_PROCESS_STATUS_RQST
11:55:08.621 [27855] <2> find_processes: total malloc-ed = 4096
11:55:08.634 [27855] <2> get_unxwre_processes: 118 files considered, 116 files opened/read
11:55:08.634 [27855] <2> find_processes: progs_list_size = 4096 == total calculated size = 4096 == (total_space_used = 3972 + whats_left = 124)
11:55:08.634 [27855] <2> find_processes: realloc down to 3972
11:55:08.681 [27855] <2> bpcd main: BPCD_DISCONNECT_RQST
11:55:08.681 [27855] <2> bpcd exit_bpcd: exit status 0  ----------->exiting
11:56:10.095 [27882] <2> bpcd main: offset to GMT -28800
11:56:10.095 [27882] <2> logconnections: BPCD ACCEPT FROM 10.87.0.198.60432 TO 10.87.0.198.13724
11:56:10.095 [27882] <2> bpcd main: setup_sockopts complete
11:56:10.105 [27881] <2> bpcd main: offset to GMT -28800
11:56:10.105 [27881] <2> logconnections: BPCD ACCEPT FROM 10.87.0.198.60430 TO 10.87.0.198.13724
11:56:10.105 [27882] <2> bpcd peer_hostname: Connection from host bckserver01 (10.87.0.198) port 60432
11:56:10.105 [27881] <2> bpcd main: setup_sockopts complete
11:56:10.114 [27882] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:56:10.114 [27882] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:56:10.115 [27881] <2> bpcd peer_hostname: Connection from host bckserver01 (10.87.0.198) port 60430
11:56:10.124 [27881] <2> VssGetFQDNHostName: vss_auth.cpp.3997: Function: VssGetFQDNHostName. Search name
11:56:10.124 [27881] <2> VssInit: vss_auth.cpp.716: Function: VssInit. Using Cached entries FALSE
11:56:10.141 [27882] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:56:10.141 [27882] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:56:10.143 [27882] <2> bpcd valid_server: comparing bckserver01 and bckserver01
11:56:10.143 [27882] <4> bpcd valid_server: hostname comparison succeeded
11:56:10.143 [27882] <2> bpcd main: output socket port number = 1
11:56:10.163 [27881] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1721: VssImportCredential failed: 14 0x0000000e
11:56:10.163 [27881] <2> vnet_vxss_import_credential: vnet_vxss_helper.c.1722: credential path:
11:56:10.164 [27881] <2> bpcd valid_server: comparing bckserver01 and bckserver01
11:56:10.165 [27881] <4> bpcd valid_server: hostname comparison succeeded
11:56:10.165 [27881] <2> bpcd main: output socket port number = 1
11:56:10.233 [27882] <2> bpcd main: Duplicated vnetd socket on stderr
11:56:10.233 [27882] <2> bpcd main: <---- NetBackup 6.0 0 ------------initiated
11:56:10.269 [27881] <2> bpcd main: Duplicated vnetd socket on stderr
11:56:10.269 [27881] <2> bpcd main: <---- NetBackup 6.0 0 ------------initiated
11:56:10.311 [27882] <2> bpcd main: Not using VxSS authentication with bckserver01
11:56:10.312 [27881] <2> bpcd main: Not using VxSS authentication with bckserver01
11:56:10.352 [27882] <2> bpcd main: BPCD_FORK_CMD_RQST
11:56:10.352 [27882] <2> bpcd main: fork cmd = /usr/openv/netbackup/bin/bptm bptm -rptdrv -jobid -1303437601 -jm
11:56:10.352 [27882] <2> bpcd main: filter type = -1
11:56:10.352 [27882] <2> bpcd main: backuptime before fork = 0
11:56:10.354 [27886] <2> bpcd main: Converting args string into an argv
11:56:10.354 [27886] <2> bpcd main: Done converting args string into an argv
11:56:10.354 [27886] <2> bpcd main: child_args[0] = /usr/openv/netbackup/bin/bptm
11:56:10.354 [27886] <2> bpcd main: child_args[1] = bptm
11:56:10.354 [27886] <2> bpcd main: child_args[2] = -rptdrv
11:56:10.354 [27886] <2> bpcd main: child_args[3] = -jobid
11:56:10.354 [27886] <2> bpcd main: child_args[4] = -1303437601
11:56:10.354 [27886] <2> bpcd main: child_args[5] = -jm
11:56:10.354 [27886] <2> bpcd main: Before execvp of command
11:56:10.362 [27881] <2> bpcd main: BPCD_GET_PROCESS_STATUS_RQST
11:56:10.363 [27882] <2> bpcd exit_bpcd: exit status 0  ----------->exiting

 


 

 
 

Marianne
Level 6
Partner    VIP    Accredited Certified

PLEASE post logs in future as attachments rather than pasting contents - it makes the post difficult to read.

The logs contain backup start, not the end of the backups. The end of backup logs are important, not the beginning.

In bptm we can see that the tape was ready (header written to tape) and waiting for first data buffer from client:

11:39:05.438 [27396] <2> io_write_back_header: drive index 2, 10.67.99.227_1304393831, file num = 3, mpx_headers = 0, copy 1
11:39:05.445 [27396] <2> write_data: completed writing backup header, start writing data when first buffer is available, copy 1

First buffer received:

11:53:16.080 [27396] <2> write_data: received first buffer (6553600 bytes), begin writing data


No further indication of any more data received from client.

The logs are of no use without seeing transfer rates at the end of the backup.

When you did the ftp test, did you ftp the SAME data that you are trying to backup (D:\baksql)?

Please have a look at this TN: http://www.symantec.com/docs/TECH60844

We have found that disabling TCP Chimney on W2003 clients dramatically increased backup speed (ftp transfer rate was also OK on these clients, but backups slow...)

Please follow recommendations in this TN to turn off/disable TCP Chimney:

" Symantec Support recommends setting all these values to Off/Disabled/None  (or whatever value means "off") "

kylix
Level 4

thanks for your reply.

I am sorry to what you said, I will follow what you said next time.

The backup job was in pending status for  a long time , it could not stop automatically. So, I can not give the end of backup log information.  If you stop the job manually, the end of bakcup log information will be useless.

I did ftp test with the same data(D:\baksql).

I will read the http://www.symantec.com/docs/TECH60844 , and try to resolve the issue.

 

thanks a lot.

kylix

 

Marianne
Level 6
Partner    VIP    Accredited Certified

What I was trying to say is that you need to wait for backups to complete (however long it takes) and then collect logs. All useful info is logged at the end of the backup... Before that, all you can see is each time bptm receives a data buffer from the client.

In addition to testing ftp with the D:\baksql data, also check disk read speed for this folder by running bpbkar32 on the client itself - see this TN for details: http://www.symantec.com/docs/TECH17541

watsons
Level 6

1) Open files

From your bpbkar log, I can see the VSP (default snapshot setting for NBU) snapshot is not created successfully. 

Without snapshot, your backup could end up waiting for response on many open files and slow down the entire progress significantly.

Since the client is Windows 2003, try to configure in master server -> host properties -> Client attributes -> add an entry for this Windows client and set to use "VSS" instead of "VSP" (enable WOFB). Restart your NBU on master server. On your client, make sure the "Volume Shadow Copy" is "enabled". Run the backup again.

2) Number of files

How many files are you trying to backup on this client? Note that a small amount of data, with up to at least million of files for backup, can be very slow. In that case, you might want to use "Flashbackup" of NBU, but that would require additional license.

RonCaplinger
Level 6

We also have some backups for one particular NBU client that ran for over 6 hours, backed up 400MB, with a total of 4,400 files.  The backup speed was just 17KB/sec.

This is a client that can back up much faster during a Full backup, but the filesystem has tens of millions of files, which take a very long time for the NBU client to go through and determine what needs to be backed up during Incremental backups. 

Once the backup starts on the client and it begins checking each file to see if it needs to be backed up (usually by looking at the archive bit, unless you have changed it to use the timestamp), the clock starts ticking for NBU's speed calculation.  So you may be getting faster backups that indicated, but NBU is just showing the overall KB/sec backup speed.