******************************************************************************************************************************************************************************************************** bptm ******************************************************************************************************************************************************************************************************** 08:30:11.684 [68246] <2> bptm: INITIATING (VERBOSE = 3): -rptdrv -jobid -1558683289 -jm 08:30:11.704 [68246] <2> drivename_open: Called with Create 0, file HP.ULTRIUM6-SCSI.008 08:30:11.704 [68246] <2> drivename_checklock: Called 08:30:11.704 [68246] <2> report_drives: DRIVE = HP.ULTRIUM6-SCSI.008 LOCK = FALSE CURTIME = 1560916811 08:30:11.704 [68246] <2> report_drives: MODE = 1 08:30:11.704 [68246] <2> report_drives: TIME = 1560860365 08:30:11.704 [68246] <2> report_drives: MASTER = MASTER_NAME 08:30:11.704 [68246] <2> report_drives: SR_KEY = 0 1 08:30:11.704 [68246] <2> report_drives: PATH = MISSING_PATH:/dev/nst7:F00286F025 08:30:11.704 [68246] <2> report_drives: MEDIA = 000208 08:30:11.704 [68246] <2> report_drives: REQID = 4022312 08:30:11.704 [68246] <2> report_drives: ALOCID = 3241163 08:30:11.704 [68246] <2> report_drives: RBID = {4D7B9598-91C3-11E9-8864-A7EA773D7C43} 08:30:11.704 [68246] <2> report_drives: PID = 23993 08:30:11.704 [68246] <2> report_drives: FILE = /usr/openv/netbackup/db/media/tpreq/drive_HP.ULTRIUM6-SCSI.008 08:30:11.704 [68246] <2> drivename_open: Called with Create 0, file HP.ULTRIUM6-SCSI.009 08:30:11.704 [68246] <2> drivename_checklock: Called 08:30:11.704 [68246] <2> report_drives: DRIVE = HP.ULTRIUM6-SCSI.009 LOCK = FALSE CURTIME = 1560916811 08:30:11.704 [68246] <2> report_drives: MODE = 2 08:30:11.704 [68246] <2> report_drives: TIME = 1557131111 08:30:11.704 [68246] <2> report_drives: MASTER = MASTER_NAME 08:30:11.704 [68246] <2> report_drives: SR_KEY = 0 1 08:30:11.704 [68246] <2> report_drives: PATH = /dev/nst4 08:30:11.704 [68246] <2> report_drives: REQID = -1553862760 08:30:11.704 [68246] <2> report_drives: ALOCID = 3128249 08:30:11.704 [68246] <2> report_drives: RBID = {76DB21A4-6FD8-11E9-A3AD-94FDDC1119A3} 08:30:11.704 [68246] <2> report_drives: PID = 79857 08:30:11.704 [68246] <2> report_drives: FILE = /usr/openv/netbackup/db/media/tpreq/drive_HP.ULTRIUM6-SCSI.009 08:30:11.704 [68246] <2> drivename_open: Called with Create 0, file HP.ULTRIUM6-SCSI.013 08:30:11.704 [68246] <2> drivename_checklock: Called 08:30:11.704 [68246] <2> report_drives: DRIVE = HP.ULTRIUM6-SCSI.013 LOCK = FALSE CURTIME = 1560916811 08:30:11.704 [68246] <2> report_drives: MODE = 2 08:30:11.704 [68246] <2> report_drives: TIME = 1560861612 08:30:11.704 [68246] <2> report_drives: MASTER = MASTER_NAME 08:30:11.704 [68246] <2> report_drives: SR_KEY = 0 1 08:30:11.704 [68246] <2> report_drives: PATH = /dev/nst7 08:30:11.704 [68246] <2> report_drives: REQID = -1558673932 08:30:11.704 [68246] <2> report_drives: ALOCID = 3241219 08:30:11.704 [68246] <2> report_drives: RBID = {36DE2726-91C6-11E9-AF2F-EE699B419513} 08:30:11.704 [68246] <2> report_drives: PID = 38363 08:30:11.704 [68246] <2> report_drives: FILE = /usr/openv/netbackup/db/media/tpreq/drive_HP.ULTRIUM6-SCSI.013 08:30:11.704 [68246] <2> drivename_open: Called with Create 0, file HP.ULTRIUM6-SCSI.018 08:30:11.704 [68246] <2> drivename_checklock: Called 08:30:11.704 [68246] <2> report_drives: DRIVE = HP.ULTRIUM6-SCSI.018 LOCK = FALSE CURTIME = 1560916811 08:30:11.704 [68246] <2> report_drives: MODE = 1 08:30:11.704 [68246] <2> report_drives: TIME = 1502714311 08:30:11.704 [68246] <2> report_drives: MASTER = MASTER_NAME 08:30:11.704 [68246] <2> report_drives: SR_KEY = 0 1 08:30:11.704 [68246] <2> report_drives: PATH = MISSING_PATH:3:0:5:0:F00286F073 08:30:11.704 [68246] <2> report_drives: MEDIA = 000279 08:30:11.704 [68246] <2> report_drives: REQID = 1373499 08:30:11.704 [68246] <2> report_drives: ALOCID = 1384990 08:30:11.704 [68246] <2> report_drives: RBID = {57700172-80EC-11E7-A8DE-BF30AA0F6E60} 08:30:11.704 [68246] <2> report_drives: PID = 68190 08:30:11.704 [68246] <2> report_drives: FILE = /usr/openv/netbackup/db/media/tpreq/drive_HP.ULTRIUM6-SCSI.018 08:30:11.704 [68246] <2> drivename_open: Called with Create 0, file HP.ULTRIUM6-SCSI.022 08:30:11.704 [68246] <2> drivename_checklock: Called 08:30:11.704 [68246] <2> report_drives: DRIVE = HP.ULTRIUM6-SCSI.022 LOCK = FALSE CURTIME = 1560916811 08:30:11.704 [68246] <2> report_drives: MODE = 2 08:30:11.704 [68246] <2> report_drives: TIME = 1454929211 08:30:11.704 [68246] <2> report_drives: MASTER = MASTER_NAME 08:30:11.704 [68246] <2> report_drives: SR_KEY = 0 1 08:30:11.704 [68246] <2> report_drives: PATH = /dev/nst2 08:30:11.704 [68246] <2> report_drives: REQID = -1454908804 08:30:11.704 [68246] <2> report_drives: ALOCID = 553764 08:30:11.704 [68246] <2> report_drives: RBID = {1F71BACE-CE53-11E5-A8FC-4A3A1E17BBD0} 08:30:11.704 [68246] <2> report_drives: PID = 70270 08:30:11.704 [68246] <2> report_drives: FILE = /usr/openv/netbackup/db/media/tpreq/drive_HP.ULTRIUM6-SCSI.022 08:30:11.704 [68246] <2> drivename_open: Called with Create 0, file HP.ULTRIUM6-SCSI.023 08:30:11.705 [68246] <2> drivename_checklock: Called 08:30:11.705 [68246] <2> report_drives: DRIVE = HP.ULTRIUM6-SCSI.023 LOCK = FALSE CURTIME = 1560916811 08:30:11.705 [68246] <2> report_drives: MODE = 2 08:30:11.705 [68246] <2> report_drives: TIME = 1552987619 08:30:11.705 [68246] <2> report_drives: MASTER = MASTER_NAME 08:30:11.705 [68246] <2> report_drives: SR_KEY = 0 1 08:30:11.705 [68246] <2> report_drives: PATH = /dev/nst3 08:30:11.705 [68246] <2> report_drives: REQID = -1540181911 08:30:11.705 [68246] <2> report_drives: ALOCID = 2984089 08:30:11.705 [68246] <2> report_drives: RBID = {255E9614-4A29-11E9-9F59-D85C4649A35F} 08:30:11.705 [68246] <2> report_drives: PID = 50840 08:30:11.705 [68246] <2> report_drives: FILE = /usr/openv/netbackup/db/media/tpreq/drive_HP.ULTRIUM6-SCSI.023 08:30:11.705 [68246] <2> drivename_open: Called with Create 0, file HP.ULTRIUM6-SCSI.029 08:30:11.705 [68246] <2> drivename_checklock: Called 08:30:11.705 [68246] <2> report_drives: DRIVE = HP.ULTRIUM6-SCSI.029 LOCK = FALSE CURTIME = 1560916811 08:30:11.705 [68246] <2> report_drives: MODE = 2 08:30:11.705 [68246] <2> report_drives: TIME = 1555404911 08:30:11.705 [68246] <2> report_drives: MASTER = MASTER_NAME 08:30:11.705 [68246] <2> report_drives: SR_KEY = 0 1 08:30:11.705 [68246] <2> report_drives: PATH = /dev/nst30 08:30:11.705 [68246] <2> report_drives: REQID = -1553572210 08:30:11.705 [68246] <2> report_drives: ALOCID = 3066634 08:30:11.705 [68246] <2> report_drives: RBID = {575C99CE-6025-11E9-8314-F91BA436EC2A} 08:30:11.705 [68246] <2> report_drives: PID = 58561 08:30:11.705 [68246] <2> report_drives: FILE = /usr/openv/netbackup/db/media/tpreq/drive_HP.ULTRIUM6-SCSI.029 08:30:11.705 [68246] <2> drivename_open: Called with Create 0, file HP.ULTRIUM6-SCSI.031 08:30:11.705 [68246] <2> drivename_checklock: Called 08:30:11.705 [68246] <2> report_drives: DRIVE = HP.ULTRIUM6-SCSI.031 LOCK = FALSE CURTIME = 1560916811 08:30:11.705 [68246] <2> report_drives: MODE = 2 08:30:11.705 [68246] <2> report_drives: TIME = 1458543111 08:30:11.705 [68246] <2> report_drives: MASTER = MASTER_NAME 08:30:11.705 [68246] <2> report_drives: SR_KEY = 0 1 08:30:11.705 [68246] <2> report_drives: PATH = /dev/nst29 08:30:11.705 [68246] <2> report_drives: REQID = -1458536936 08:30:11.705 [68246] <2> report_drives: ALOCID = 654837 08:30:11.705 [68246] <2> report_drives: RBID = {587D0786-EF2F-11E5-886B-88FE24341158} 08:30:11.705 [68246] <2> report_drives: PID = 21585 08:30:11.705 [68246] <2> report_drives: FILE = /usr/openv/netbackup/db/media/tpreq/drive_HP.ULTRIUM6-SCSI.031 08:30:11.705 [68246] <2> drivename_open: Called with Create 0, file HP.ULTRIUM6-SCSI.035 08:30:11.705 [68246] <2> drivename_checklock: Called 08:30:11.705 [68246] <2> report_drives: DRIVE = HP.ULTRIUM6-SCSI.035 LOCK = FALSE CURTIME = 1560916811 08:30:11.705 [68246] <2> report_drives: MODE = 2 08:30:11.705 [68246] <2> report_drives: TIME = 1454929211 08:30:11.705 [68246] <2> report_drives: MASTER = MASTER_NAME 08:30:11.705 [68246] <2> report_drives: SR_KEY = 0 1 08:30:11.705 [68246] <2> report_drives: PATH = /dev/nst31 08:30:11.705 [68246] <2> report_drives: REQID = -1454908807 08:30:11.705 [68246] <2> report_drives: ALOCID = 553767 08:30:11.705 [68246] <2> report_drives: RBID = {1F845FEE-CE53-11E5-A2AE-9D8E013A0430} 08:30:11.705 [68246] <2> report_drives: PID = 70271 08:30:11.705 [68246] <2> report_drives: FILE = /usr/openv/netbackup/db/media/tpreq/drive_HP.ULTRIUM6-SCSI.035 08:30:11.705 [68246] <2> drivename_open: Called with Create 0, file HP.ULTRIUM6-SCSI.038 08:30:11.705 [68246] <2> drivename_checklock: Called 08:30:11.705 [68246] <2> report_drives: DRIVE = HP.ULTRIUM6-SCSI.038 LOCK = FALSE CURTIME = 1560916811 08:30:11.705 [68246] <2> report_drives: MODE = 2 08:30:11.705 [68246] <2> report_drives: TIME = 1458543111 08:30:11.705 [68246] <2> report_drives: MASTER = MASTER_NAME 08:30:11.705 [68246] <2> report_drives: SR_KEY = 0 1 08:30:11.705 [68246] <2> report_drives: PATH = /dev/nst24 08:30:11.705 [68246] <2> report_drives: REQID = -1458536938 08:30:11.705 [68246] <2> report_drives: ALOCID = 654839 08:30:11.705 [68246] <2> report_drives: RBID = {5885C9A2-EF2F-11E5-ADE7-2D5935698E71} 08:30:11.705 [68246] <2> report_drives: PID = 21588 08:30:11.705 [68246] <2> report_drives: FILE = /usr/openv/netbackup/db/media/tpreq/drive_HP.ULTRIUM6-SCSI.038 08:30:11.705 [68246] <2> drivename_open: Called with Create 0, file HP.ULTRIUM6-SCSI.042 08:30:11.705 [68246] <2> drivename_checklock: Called 08:30:11.705 [68246] <2> report_drives: DRIVE = HP.ULTRIUM6-SCSI.042 LOCK = FALSE CURTIME = 1560916811 08:30:11.705 [68246] <2> report_drives: MODE = 2 08:30:11.705 [68246] <2> report_drives: TIME = 1552915028 08:30:11.705 [68246] <2> report_drives: MASTER = MASTER_NAME 08:30:11.705 [68246] <2> report_drives: SR_KEY = 0 1 08:30:11.705 [68246] <2> report_drives: PATH = /dev/nst27 08:30:11.705 [68246] <2> report_drives: REQID = -1540170302 08:30:11.705 [68246] <2> report_drives: ALOCID = 2982236 08:30:11.705 [68246] <2> report_drives: RBID = {1C799548-4980-11E9-9C89-3B8E50185BAE} 08:30:11.705 [68246] <2> report_drives: PID = 52685 08:30:11.705 [68246] <2> report_drives: FILE = /usr/openv/netbackup/db/media/tpreq/drive_HP.ULTRIUM6-SCSI.042 08:30:11.705 [68246] <2> main: Sending [EXIT STATUS 0] to NBJM 08:30:11.706 [68246] <2> bptm: EXITING with status 0 <---------- 08:31:53.050 [69314] <2> bptm: INITIATING (VERBOSE = 3): -dup -cmd -nosig -L /usr/openv/netbackup/logs/user_ops/root/logs/jbpDuplicate-20190619083149.log -ru root -rclnt MASTER_NAME -jobid 4025533 -mediasvr MEDIA_Y -c MEDIA_X -b MEDIA_X_1443523049 -cl MEDIATION-DD-PH-ARC-backup03-MEDIA_X -sl MEDIA_X-backup03-dd -bt 1443523049 -st 0 -rl 24 -date 2147483647 -ct 0 -den 20 -rt 8 -rn 2 -p Mediation-Archive -twin_fail_on_error 0 -cn 2 -maxfrag 1048576 -stunit MEDIA_Y-hcart3-Qi6K-tld-2 -cj 20 -sg *ANY* -use_vnetd -df 1 08:31:53.070 [69314] <8> file_to_cache_item: [vnet_addrinfo.c:6555] fopen() failed ERRNO=2 FILE=/usr/openv/var/host_cache/0b9/d74f64b9+veritas_pbx,1,20,2,1,0+MASTER_NAME.txt 08:31:53.093 [69314] <8> file_to_cache_item: [vnet_addrinfo.c:6555] fopen() failed ERRNO=2 FILE=/usr/openv/var/host_cache/0b9/d74f64b9+vnetd,1,20,2,1,0+MASTER_NAME.txt 08:31:53.114 [69314] <8> file_to_cache_item: [vnet_addrinfo.c:6555] fopen() failed ERRNO=2 FILE=/usr/openv/var/host_cache/0b9/d74f64b9+bpjobd,1,20,2,1,0+MASTER_NAME.txt 08:31:53.139 [69314] <2> vnet_pbxConnect: pbxConnectEx Succeeded 08:31:53.140 [69314] <2> job_connect: SO_KEEPALIVE set on socket 1 for client MASTER_NAME 08:31:53.140 [69314] <2> logconnections: BPJOBD CONNECT FROM MEDIA_Y_IP.41403 TO MASTER_IP.1556 fd = 1 08:31:53.140 [69314] <2> job_authenticate_connection: ignoring VxSS authentication check for now... 08:31:53.144 [69314] <2> job_connect: Connected to the host MASTER_NAME contype 53 jobid <4025533> socket <1> 08:31:53.144 [69314] <2> job_connect: Connected on port 41403 08:31:53.144 [69314] <8> vnet_same_host_and_update: [vnet_addrinfo.c:2820] name2 is empty 0 0x0 08:31:53.170 [69314] <4> bptm: emmserver_name = MASTER_NAME 08:31:53.170 [69314] <4> bptm: emmserver_port = 1556 08:31:53.178 [69314] <2> Orb::setDebugLevelFromVxul: Orb logging configuration level set to 0(Orb.cpp:2261) 08:31:53.178 [69314] <2> emmlib_initialize: (-) Connection attempt #<0> 08:31:53.178 [69314] <2> emmlib_initializeEx: (-) Connecting to the Server Port <1556>, App OrbOpt 08:31:53.184 [69314] <2> SetMaxFileNum: current max fileno: 4096, max fileno hard limit: 4096(SetMaxFileNum.cpp:34) 08:31:53.184 [69314] <2> SetMaxFileNum: successfully set max fileno: current=4096, max=4096(SetMaxFileNum.cpp:90) 08:31:53.189 [69314] <2> Orb::setDebugLevelFromVxul: Orb logging configuration level set to 0(Orb.cpp:2261) 08:31:53.189 [69314] <2> Orb::init: checking if NBAC is enabled(Orb.cpp:508) 08:31:53.211 [69314] <2> EndpointSelector_R2::updateConfMembers: epsr2: bp.conf changed since last look, reloading(Endpoint_Selector.cpp:384) 08:31:53.212 [69314] <2> EndpointSelectorFactory::EndpointSelectorFactory: Endpoint Selector model: 7.1 Flags: 0(Endpoint_Selector.cpp:1805) 08:31:53.212 [69314] <2> EndpointSelector_R2::updateConfMembers: epsr2: bp.conf changed since last look, reloading(Endpoint_Selector.cpp:384) 08:31:53.212 [69314] <2> InsecureEndpointSelectorFactory::InsecureEndpointSelectorFactory: Endpoint Selector model: 7.1 Flags: 0 [Ignoring BPCONF server list](Endpoint_Selector.cpp:1900) 08:31:53.212 [69314] <2> checkService: found service: UTF16_BOM_Factory(Orb.cpp:484) 08:31:53.212 [69314] <2> checkService: found service: UTF8-Latin1_Factory(Orb.cpp:484) 08:31:53.212 [69314] <2> LogMaxFileNum: current max fileno: 4096, max fileno hard limit: 4096(SetMaxFileNum.cpp:113) 08:31:53.212 [69314] <8> file_to_cache_item: [vnet_addrinfo.c:6555] fopen() failed ERRNO=0 FILE=/usr/openv/var/host_cache/1ff/ffffffff+veritas_pbx,1,1,0,1,0+.txt 08:31:53.215 [69314] <2> Orb::getConfig: found service entry: name = veritas_pbx port = 1556 proto = tcp(Orb.cpp:402) 08:31:53.215 [69314] <2> Orb::getConfig: cluster_name and required_interface not defined, using ANY(Orb.cpp:454) 08:31:53.215 [69314] <2> Orb::init: Enabling ORBNativeCharCodeSet UTF-8(Orb.cpp:594) 08:31:53.215 [69314] <2> Orb::init: initializing ORB with: bptm -ORBSvcConfDirective "-ORBDottedDecimalAddresses 0" -ORBSvcConfDirective "static Resource_Factory '-ORBNativeCharCodeSet UTF-8'" -ORBSvcConfDirective "static PBXIOP_Factory '-enable_keepalive'" -ORBSvcConfDirective "static EndpointSelectorFactory ''" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory PBXIOP_Factory'" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory IIOP_Factory'" -ORBDefaultInitRef '' -ORBSvcConfDirective "static PBXIOP_Evaluator_Factory '-orb EMMlib_Orb'"(Orb.cpp:640) 08:31:53.215 [69314] <2> Orb::init: added args has value of: (Orb.cpp:770) 08:31:53.216 [69314] <2> Orb::init: initializing ORB EMMlib_Orb with: bptm -ORBSvcConfDirective "-ORBDottedDecimalAddresses 0" -ORBSvcConfDirective "static Resource_Factory '-ORBNativeCharCodeSet UTF-8'" -ORBSvcConfDirective "static PBXIOP_Factory '-enable_keepalive'" -ORBSvcConfDirective "static EndpointSelectorFactory ''" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory PBXIOP_Factory'" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory IIOP_Factory'" -ORBDefaultInitRef '' -ORBSvcConfDirective "static PBXIOP_Evaluator_Factory '-orb EMMlib_Orb'" -ORBSvcConfDirective "static Resource_Factory '-ORBConnectionCacheMax 1024 '" -ORBSvcConf /dev/null -ORBSvcConfDirective "static Server_Strategy_Factory '-ORBMaxRecvGIOPPayloadSize 268435456'"(Orb.cpp:825) 08:31:53.217 [69314] <2> NBOrbInitializer::post_init: Adding interceptors(NBOrbInitializer.cpp:74) 08:31:53.217 [69314] <2> Orb::init: caching EndpointSelectorFactory(Orb.cpp:840) 08:31:53.217 [69314] <2> Orb::setOrbTimeoutPolicy: setting ORB connection timeout policy: tv = 30000(Orb.cpp:1504) 08:31:53.217 [69314] <2> Orb::activate: Client Orb called activate; Ignored(Orb.cpp:1236) 08:31:53.217 [69314] <2> Orb::setOrbTimeoutPolicy: setting ORB connection timeout policy: tv = 60(Orb.cpp:1504) 08:31:53.217 [69314] <2> Orb::setOrbConnectTimeout: timeout seconds: 60(Orb.cpp:1481) 08:31:53.217 [69314] <2> emmlib_initializeEx: (-) Setting connect timeout to <60> sec 08:31:53.217 [69314] <2> Orb::setOrbTimeoutPolicy: setting ORB request timeout policy: tv = 1800(Orb.cpp:1504) 08:31:53.217 [69314] <2> Orb::setOrbRequestTimeout: timeout seconds: 1800(Orb.cpp:1490) 08:31:53.217 [69314] <2> emmlib_initializeEx: (-) Setting request timeout to <1800> sec 08:31:53.217 [69314] <2> Orb::connectToObject: Connection attempt #1 mW:60 rW:0(Orb.cpp:1167) 08:31:53.217 [69314] <2> Orb::createInsecureObjectRef: corbaloc being used is: corbaloc:pbxiop:MASTER_NAME:1556:EMM/EMMServer(Orb.cpp:1440) 08:31:53.218 [69314] <2> Orb::connectToObjectOnce: attempt to create insecure connection(Orb.cpp:1034) 08:31:53.218 [69314] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:507) 08:31:53.218 [69314] <8> file_to_cache_item: [vnet_addrinfo.c:6555] fopen() failed ERRNO=0 FILE=/usr/openv/var/host_cache/0b9/d74f64b9+0,1,422,2,1,0+MASTER_NAME.txt 08:31:53.236 [69314] <2> EndpointSelector_R2::cachedHostLookup: epsr2: Hostname MASTER_NAME has 1 candidate addresses(Endpoint_Selector.cpp:1605) 08:31:53.236 [69314] <8> file_to_cache_item: [vnet_addrinfo.c:6555] fopen() failed ERRNO=0 FILE=/usr/openv/var/host_cache/08d/b517c48d+0,1,420,2,1,0+MASTER_IP.txt 08:31:53.236 [69314] <2> EndpointSelector_R2::performCallUsingEndpointAndSourceBinding: epsr2: Target: MASTER_IP, source: ANY(Endpoint_Selector.cpp:939) 08:31:53.238 [69314] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure invocation->perform_call(...) to host MASTER_IP SUCCESS(Endpoint_Selector.cpp:1149) 08:31:53.245 [69314] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:507) 08:31:53.245 [69314] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Reusing endpoint, target: MASTER_IP, source: ANY(Endpoint_Selector.cpp:659) 08:31:53.246 [69314] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure invocation->perform_call(...) to host MASTER_IP SUCCESS(Endpoint_Selector.cpp:1149) 08:31:53.247 [69314] <2> Orb::connectToObjectOnce: connection created(Orb.cpp:1052) 08:31:53.247 [69314] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:507) 08:31:53.247 [69314] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Reusing endpoint, target: MASTER_IP, source: ANY(Endpoint_Selector.cpp:659) 08:31:53.247 [69314] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure invocation->perform_call(...) to host MASTER_IP SUCCESS(Endpoint_Selector.cpp:1149) 08:31:53.249 [69314] <4> CreateConnectionID: (-) Using existing uninitialized Connection ID 0 08:31:53.249 [69314] <4> emmlib_initializeEx: (0) Successfully initialized EMM session 08:31:53.249 [69314] <2> main: Setting mud from bp.conf 08:31:53.249 [69314] <2> nbjm_media_request: Passing job control to NBJM, type WRITE/9 08:31:53.249 [69314] <2> nbjm_media_request: old_media_id = , media_id = NULL 08:31:53.249 [69314] <2> SetMaxFileNum: current max fileno: 4096, max fileno hard limit: 4096(SetMaxFileNum.cpp:34) 08:31:53.249 [69314] <2> SetMaxFileNum: successfully set max fileno: current=4096, max=4096(SetMaxFileNum.cpp:90) 08:31:53.249 [69314] <2> Orb::init: checking if NBAC is enabled(Orb.cpp:508) 08:31:53.249 [69314] <2> checkService: found service: UTF16_BOM_Factory(Orb.cpp:484) 08:31:53.249 [69314] <2> checkService: found service: UTF8-Latin1_Factory(Orb.cpp:484) 08:31:53.249 [69314] <2> LogMaxFileNum: current max fileno: 4096, max fileno hard limit: 4096(SetMaxFileNum.cpp:113) 08:31:53.250 [69314] <2> Orb::getConfig: found service entry: name = veritas_pbx port = 1556 proto = tcp(Orb.cpp:402) 08:31:53.250 [69314] <2> Orb::getConfig: cluster_name and required_interface not defined, using ANY(Orb.cpp:454) 08:31:53.250 [69314] <2> Orb::init: Enabling ORBNativeCharCodeSet UTF-8(Orb.cpp:594) 08:31:53.250 [69314] <2> Orb::init: substituting orb name of: Default_DAEMON_Orb(Orb.cpp:621) 08:31:53.250 [69314] <2> Orb::init: initializing ORB with: Unknown -ORBSvcConfDirective "-ORBDottedDecimalAddresses 0" -ORBSvcConfDirective "static Resource_Factory '-ORBNativeCharCodeSet UTF-8'" -ORBSvcConfDirective "static PBXIOP_Factory '-enable_keepalive'" -ORBSvcConfDirective "static EndpointSelectorFactory ''" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory PBXIOP_Factory'" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory IIOP_Factory'" -ORBDefaultInitRef '' -ORBSvcConfDirective "static PBXIOP_Evaluator_Factory '-orb Default_DAEMON_Orb'"(Orb.cpp:640) 08:31:53.250 [69314] <2> Orb::init: Created anon service name: NB_69314_1708227840(Orb.cpp:697) 08:31:53.250 [69314] <2> Orb::init: endpointvalue is : pbxiop://1556:NB_69314_1708227840(Orb.cpp:714) 08:31:53.250 [69314] <2> Orb::init: added args has value of: (Orb.cpp:770) 08:31:53.250 [69314] <2> Orb::init: initializing ORB Default_DAEMON_Orb with: Unknown -ORBSvcConfDirective "-ORBDottedDecimalAddresses 0" -ORBSvcConfDirective "static Resource_Factory '-ORBNativeCharCodeSet UTF-8'" -ORBSvcConfDirective "static PBXIOP_Factory '-enable_keepalive'" -ORBSvcConfDirective "static EndpointSelectorFactory ''" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory PBXIOP_Factory'" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory IIOP_Factory'" -ORBDefaultInitRef '' -ORBSvcConfDirective "static PBXIOP_Evaluator_Factory '-orb Default_DAEMON_Orb'" -ORBSvcConfDirective "static Resource_Factory '-ORBConnectionCacheMax 1024 '" -ORBEndpoint pbxiop://1556:NB_69314_1708227840 -ORBSvcConf /dev/null -ORBSvcConfDirective "static Server_Strategy_Factory '-ORBMaxRecvGIOPPayloadSize 268435456'"(Orb.cpp:825) 08:31:53.251 [69314] <2> NBOrbInitializer::post_init: Adding interceptors(NBOrbInitializer.cpp:74) 08:31:53.251 [69314] <2> Orb::init: caching EndpointSelectorFactory(Orb.cpp:840) 08:31:53.251 [69314] <2> Orb::setOrbTimeoutPolicy: setting ORB connection timeout policy: tv = 30000(Orb.cpp:1504) 08:31:53.298 [69314] <8> copy_addrinfo: [vnet_addrinfo.c:3692] no valid addresses to copy 0 0x0 08:31:53.298 [69314] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1636] copy_addrinfo() failed NAME=fe80::da9d:67ff:fe77:62cd%9 STAT=6 08:31:53.298 [69314] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1273] vnet_cached_getaddrinfo_and_update() failed 6 0x6 08:31:53.298 [69314] <2> ExternalResolver::set: Could not resolve host_name: fe80::da9d:67ff:fe77:62cd%9 status/errval: 6(0)(ExternalResolver.cpp:41) 08:31:53.298 [69314] <8> file_to_cache_item: [vnet_addrinfo.c:6555] fopen() failed ERRNO=0 FILE=/usr/openv/var/host_cache/19c/7ecd659c+0,1,422,0,1,0+fe80::21b:21ff:febb:b7b5%10.txt 08:31:53.303 [69314] <8> copy_addrinfo: [vnet_addrinfo.c:3692] no valid addresses to copy 0 0x0 08:31:53.303 [69314] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1636] copy_addrinfo() failed NAME=fe80::21b:21ff:febb:b7b5%10 STAT=6 08:31:53.303 [69314] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1273] vnet_cached_getaddrinfo_and_update() failed 6 0x6 08:31:53.303 [69314] <2> ExternalResolver::set: Could not resolve host_name: fe80::21b:21ff:febb:b7b5%10 status/errval: 6(0)(ExternalResolver.cpp:41) 08:31:53.317 [69314] <8> file_to_cache_item: [vnet_addrinfo.c:6555] fopen() failed ERRNO=0 FILE=/usr/openv/var/host_cache/168/27eb7d68+0,1,402,2,1,0+MEDIA_Y.txt 08:31:53.318 [69314] <2> NBIORInterceptor::establish_components: Encoding IP Address [MEDIA_Y_IP] in IOR(NBIORInterceptor.cpp:117) 08:31:53.319 [69314] <2> Orb::connectToObject: Connection attempt #1 mW:30 rW:0(Orb.cpp:1167) 08:31:53.319 [69314] <2> Orb::createInsecureObjectRef: corbaloc being used is: corbaloc:pbxiop:MASTER_NAME:1556:nbjm/JOBINST(Orb.cpp:1440) 08:31:53.319 [69314] <2> Orb::connectToObjectOnce: attempt to create insecure connection(Orb.cpp:1034) 08:31:53.319 [69314] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:507) 08:31:53.342 [69314] <2> EndpointSelector_R2::cachedHostLookup: epsr2: Hostname MASTER_NAME has 1 candidate addresses(Endpoint_Selector.cpp:1605) 08:31:53.343 [69314] <2> EndpointSelector_R2::performCallUsingEndpointAndSourceBinding: epsr2: Host resolved to GOOD address, target: MASTER_IP, source: ANY(Endpoint_Selector.cpp:903) 08:31:53.343 [69314] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure invocation->perform_call(...) to host MASTER_IP SUCCESS(Endpoint_Selector.cpp:1149) 08:31:53.347 [69314] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:507) 08:31:53.348 [69314] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Reusing endpoint, target: MASTER_IP, source: ANY(Endpoint_Selector.cpp:659) 08:31:53.348 [69314] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure invocation->perform_call(...) to host MASTER_IP SUCCESS(Endpoint_Selector.cpp:1149) 08:31:53.349 [69314] <2> Orb::connectToObjectOnce: connection created(Orb.cpp:1052) 08:31:53.349 [69314] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:507) 08:31:53.349 [69314] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Reusing endpoint, target: MASTER_IP, source: ANY(Endpoint_Selector.cpp:659) 08:31:53.350 [69314] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure invocation->perform_call(...) to host MASTER_IP SUCCESS(Endpoint_Selector.cpp:1149) 08:31:53.351 [69314] <2> packageInitialResourceRequest: started 08:31:53.351 [69314] <2> packageInitialResourceRequest: retVal = 0 08:31:53.351 [69314] <2> Orb::createObjectRef: creating object reference(Orb.cpp:2171) 08:31:53.351 [69314] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:507) 08:31:53.352 [69314] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Reusing endpoint, target: MASTER_IP, source: ANY(Endpoint_Selector.cpp:659) 08:31:53.352 [69314] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure invocation->perform_call(...) to host MASTER_IP SUCCESS(Endpoint_Selector.cpp:1149) 08:31:53.354 [69314] <2> RequestInitialResources: BpxmiV->requestResourcesWithTimeout returns 0 08:31:53.354 [69314] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:507) 08:31:53.354 [69314] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Reusing endpoint, target: MASTER_IP, source: ANY(Endpoint_Selector.cpp:659) 08:31:53.354 [69314] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure invocation->perform_call(...) to host MASTER_IP SUCCESS(Endpoint_Selector.cpp:1149) 08:31:53.357 [69314] <2> InsecureServerEvaluator::allow_peer: Allowing peer to connect(SecureEvaluator.cpp:403) 08:31:53.357 [69314] <2> resourcesAvailable: m_allocationSeq.length() == 1 08:31:53.357 [69314] <2> resourcesAvailable: thisAllocation->mdar.Bsr.BptmStrings.length() == 7 08:31:53.357 [69314] <2> resourcesAvailable: MEDIADB 1 3243115 T10086 4008795 ------ 20 1560916911 0 0 0 0 0 0 24 11 0 0 0 0 0 0 08:31:53.357 [69314] <2> resourcesAvailable: VOLUME 1 T10086 4008795 T10086L6 Mediation-Archive QUANTUM 0170420231 24 8 2 1147 0 {00000000-0000-0000-0000-000000000000} 0 08:31:53.357 [69314] <2> resourcesAvailable: DRIVE 3 HP.ULTRIUM6-SCSI.043 2000597 F00286F085 /dev/nst14 -1 -1 -1 -1 -1 -1 -1 -1 *NULL* *NULL* *NULL* *NULL* 1 0 0 1 0 0 08:31:53.357 [69314] <2> resourcesAvailable: STORAGE 1 MEDIA_Y-hcart3-Qi6K-tld-2 20 1048576 2 1 0 0 MEDIA_Y MEDIA_Y *NULL* 08:31:53.357 [69314] <2> resourcesAvailable: DISKGROUP 0 6 *NULL* 6 *NULL* 6 *NULL* 08:31:53.358 [69314] <2> resourcesAvailable: DISKVOLUME 0 6 *NULL* 6 *NULL* 0 08:31:53.358 [69314] <2> resourcesAvailable: DISKMOUNTPOINT 0 6 *NULL* 08:31:53.358 [69314] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:507) 08:31:53.358 [69314] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Reusing endpoint, target: MASTER_IP, source: ANY(Endpoint_Selector.cpp:659) 08:31:53.358 [69314] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure invocation->perform_call(...) to host MASTER_IP SUCCESS(Endpoint_Selector.cpp:1149) 08:31:53.359 [69314] <2> packageInitialResourceRequestResult: retVal = 0 08:31:53.359 [69314] <2> RequestInitialResources: retVal = 0 emmStatus = 0 08:31:53.359 [69314] <2> RequestInitialResources: returning 08:31:53.359 [69314] <2> populateBptmOpaqueStrings: packaging bptm string [MEDIADB 1 3243115 T10086 4008795 ------ 20 1560916911 0 0 0 0 0 0 24 11 0 0 0 0 0 0], length 83 08:31:53.359 [69314] <2> populateBptmOpaqueStrings: packaging bptm string [VOLUME 1 T10086 4008795 T10086L6 Mediation-Archive QUANTUM 0170420231 24 8 2 1147 0 {00000000-0000-0000-0000-000000000000} 0], length 124 08:31:53.360 [69314] <2> populateBptmOpaqueStrings: packaging bptm string [DRIVE 3 HP.ULTRIUM6-SCSI.043 2000597 F00286F085 /dev/nst14 -1 -1 -1 -1 -1 -1 -1 -1 *NULL* *NULL* *NULL* *NULL* 1 0 0 1 0 0], length 122 08:31:53.360 [69314] <2> populateBptmOpaqueStrings: packaging bptm string [STORAGE 1 MEDIA_Y-hcart3-Qi6K-tld-2 20 1048576 2 1 0 0 MEDIA_Y MEDIA_Y *NULL*], length 95 08:31:53.360 [69314] <2> populateBptmOpaqueStrings: packaging bptm string [DISKGROUP 0 6 *NULL* 6 *NULL* 6 *NULL*], length 38 08:31:53.360 [69314] <2> populateBptmOpaqueStrings: packaging bptm string [DISKVOLUME 0 6 *NULL* 6 *NULL* 0], length 32 08:31:53.360 [69314] <2> populateBptmOpaqueStrings: packaging bptm string [DISKMOUNTPOINT 0 6 *NULL*], length 25 08:31:53.360 [69314] <2> parse_resource_strings: MEDIADB 1 3243115 T10086 4008795 ------ 20 1560916911 0 0 0 0 0 0 24 11 0 0 0 0 0 0 08:31:53.360 [69314] <2> parse_resource_strings: Parsed message type 15, version 1, 21 parameters 08:31:53.360 [69314] <2> parse_resource_strings: VOLUME 1 T10086 4008795 T10086L6 Mediation-Archive QUANTUM 0170420231 24 8 2 1147 0 {00000000-0000-0000-0000-000000000000} 0 08:31:53.360 [69314] <2> parse_resource_strings: Parsed message type 16, version 1, 14 parameters 08:31:53.360 [69314] <2> parse_resource_strings: DRIVE 3 HP.ULTRIUM6-SCSI.043 2000597 F00286F085 /dev/nst14 -1 -1 -1 -1 -1 -1 -1 -1 *NULL* *NULL* *NULL* *NULL* 1 0 0 1 0 0 08:31:53.360 [69314] <2> parse_resource_strings: Parsed message type 17, version 3, 23 parameters 08:31:53.360 [69314] <2> parse_resource_strings: STORAGE 1 MEDIA_Y-hcart3-Qi6K-tld-2 20 1048576 2 1 0 0 MEDIA_Y MEDIA_Y *NULL* 08:31:53.360 [69314] <2> parse_resource_strings: Parsed message type 18, version 1, 11 parameters 08:31:53.360 [69314] <2> parse_resource_strings: DISKGROUP 0 6 *NULL* 6 *NULL* 6 *NULL* 08:31:53.360 [69314] <2> parse_resource_strings: Parsed message type 21, version 0, 7 parameters 08:31:53.360 [69314] <2> parse_resource_strings: DISKVOLUME 0 6 *NULL* 6 *NULL* 0 08:31:53.360 [69314] <2> parse_resource_strings: Parsed message type 22, version 0, 6 parameters 08:31:53.360 [69314] <2> parse_resource_strings: DISKMOUNTPOINT 0 6 *NULL* 08:31:53.360 [69314] <2> parse_resource_strings: Parsed message type 23, version 0, 3 parameters 08:31:53.360 [69314] <2> nbjm_media_request: Job control returned to BPTM 08:31:53.360 [69314] <2> ConnectionCache::connectAndCache: Acquiring new connection for host MASTER_NAME, query type 55 08:31:53.407 [69314] <8> file_to_cache_item: [vnet_addrinfo.c:6555] fopen() failed ERRNO=2 FILE=/usr/openv/var/host_cache/0b9/d74f64b9+bpdbm,1,20,2,1,0+MASTER_NAME.txt 08:31:53.432 [69314] <2> vnet_pbxConnect: pbxConnectEx Succeeded 08:31:53.433 [69314] <2> logconnections: BPDBM CONNECT FROM MEDIA_Y_IP.52498 TO MASTER_IP.1556 fd = 18 08:31:53.433 [69314] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2 08:31:53.441 [69314] <2> db_end: Need to collect reply 08:31:53.441 [69314] <2> io_init: using 65536 data buffer size 08:31:53.441 [69314] <2> io_init: CINDEX 0, sched Kbytes for monitoring = 400000 08:31:53.441 [69314] <2> io_init: using 30 data buffers 08:31:53.442 [69314] <2> io_init: child delay = 10, parent delay = 15 (milliseconds) 08:31:53.442 [69314] <2> create_shared_memory: shm_size = 1966808, buffer address = 0x0x7fcbeffc7000, buf control = 0x0x7fcbf01a7000, ready ptr = 0x0x7fcbf01a72d0 08:31:53.442 [69314] <2> ConnectionCache::connectAndCache: Acquiring new connection for host MASTER_NAME, query type 21 08:31:53.512 [69314] <2> vnet_pbxConnect: pbxConnectEx Succeeded 08:31:53.513 [69314] <2> logconnections: BPDBM CONNECT FROM MEDIA_Y_IP.23814 TO MASTER_IP.1556 fd = 18 08:31:53.513 [69314] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2 08:31:53.519 [69314] <2> db_end: Need to collect reply 08:31:53.520 [69314] <2> ConnectionCache::connectAndCache: Acquiring new connection for host MASTER_NAME, query type 1 08:31:53.590 [69314] <2> vnet_pbxConnect: pbxConnectEx Succeeded 08:31:53.591 [69314] <2> logconnections: BPDBM CONNECT FROM MEDIA_Y_IP.11476 TO MASTER_IP.1556 fd = 18 08:31:53.591 [69314] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2 08:31:53.598 [69314] <2> db_end: Need to collect reply 08:31:53.599 [69314] <4> report_client: VBRC 2 69314 1 MEDIA_X_1443523049 0 MEDIATION-DD-PH-ARC-backup03-MEDIA_X 0 MEDIA_X-backup03-dd 0 1 2 08:31:53.599 [69314] <2> ConnectionCache::connectAndCache: Acquiring new connection for host MASTER_NAME, query type 81 08:31:53.672 [69314] <2> vnet_pbxConnect: pbxConnectEx Succeeded 08:31:53.673 [69314] <2> logconnections: BPDBM CONNECT FROM MEDIA_Y_IP.51449 TO MASTER_IP.1556 fd = 18 08:31:53.673 [69314] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2 08:31:53.829 [69314] <2> db_end: Need to collect reply 08:31:53.829 [69314] <2> ConnectionCache::connectAndCache: Acquiring new connection for host MASTER_NAME, query type 243 08:31:53.910 [69314] <2> vnet_pbxConnect: pbxConnectEx Succeeded 08:31:53.910 [69314] <2> logconnections: BPDBM CONNECT FROM MEDIA_Y_IP.22481 TO MASTER_IP.1556 fd = 18 08:31:53.910 [69314] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2 08:31:53.917 [69314] <2> db_end: Need to collect reply 08:31:54.018 [69314] <2> ConnectionCache::connectAndCache: Acquiring new connection for host MASTER_NAME, query type 81 08:31:54.088 [69314] <2> vnet_pbxConnect: pbxConnectEx Succeeded 08:31:54.088 [69314] <2> logconnections: BPDBM CONNECT FROM MEDIA_Y_IP.50200 TO MASTER_IP.1556 fd = 18 08:31:54.088 [69314] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2 08:31:54.245 [69314] <2> db_end: Need to collect reply 08:31:54.246 [69314] <2> drivename_open: Called with Create 1, file HP.ULTRIUM6-SCSI.043 08:31:54.246 [69314] <2> drivename_lock: lock established 08:31:54.246 [69314] <2> drivename_write: Called with mode 0 08:31:54.246 [69314] <2> db_byid: search for media id T10086 08:31:54.246 [69314] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:507) 08:31:54.247 [69314] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Reusing endpoint, target: MASTER_IP, source: ANY(Endpoint_Selector.cpp:659) 08:31:54.247 [69314] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure invocation->perform_call(...) to host MASTER_IP SUCCESS(Endpoint_Selector.cpp:1149) 08:31:54.248 [69314] <4> emmlib_MediaQueryOne: (0) V_QUERY_BYID/V_QUERY_BYID_DEBUG request (T10086 0 0) 08:31:54.248 [69314] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:507) 08:31:54.249 [69314] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Reusing endpoint, target: MASTER_IP, source: ANY(Endpoint_Selector.cpp:659) 08:31:54.249 [69314] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure invocation->perform_call(...) to host MASTER_IP SUCCESS(Endpoint_Selector.cpp:1149) 08:31:54.259 [69314] <4> emmlib_MediaQueryOne: (0) CORBA call returned 0 08:31:54.259 [69314] <2> send_MDS_msg: MEDIADB 1 3243115 T10086 4008795 *NULL* 20 1560916913 0 0 0 0 0 0 24 11 0 16 1024 0 0 0 08:31:54.259 [69314] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:507) 08:31:54.260 [69314] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Reusing endpoint, target: MASTER_IP, source: ANY(Endpoint_Selector.cpp:659) 08:31:54.260 [69314] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure invocation->perform_call(...) to host MASTER_IP SUCCESS(Endpoint_Selector.cpp:1149) 08:31:54.261 [69314] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:507) 08:31:54.262 [69314] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Reusing endpoint, target: MASTER_IP, source: ANY(Endpoint_Selector.cpp:659) 08:31:54.262 [69314] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure invocation->perform_call(...) to host MASTER_IP SUCCESS(Endpoint_Selector.cpp:1149) 08:31:54.272 [69314] <2> emmlib_handleMessage: (0) CORBA call returned 0 08:31:54.272 [69314] <2> media_id_to_monitor: job_id = 4025533, pDstMediaId = T10086 08:31:54.272 [69314] <2> create_data_socket: tmcommon.c.6876: IPC, TIRIPC, PID, blksize: IPC /tmp/vnet-69314560916914272741000000010-Szin2V NULL 69314 0 08:31:54.470 [69314] <2> create_data_socket: data socket created is 21, duping to stdin 08:31:54.471 [69314] <2> write_backup: backup child process is pid 69360 08:31:54.471 [69314] <2> mount_open_media: Waiting for mount of media id T10086 (copy 2) on server MEDIA_Y. 08:31:54.471 [69360] <2> close_disk_descriptors: descriptor close: files = FALSE sockets = FALSE 08:31:54.471 [69314] <2> ConnectionCache::connectAndCache: Acquiring new connection for host MASTER_NAME, query type 223 08:31:54.542 [69314] <2> vnet_pbxConnect: pbxConnectEx Succeeded 08:31:54.542 [69314] <2> logconnections: BPDBM CONNECT FROM MEDIA_Y_IP.64128 TO MASTER_IP.1556 fd = 0 08:31:54.542 [69314] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2 08:31:54.549 [69314] <2> db_CLIENTsend: reset client protocol version from 0 to 8 08:31:54.589 [69314] <2> db_getCLIENT: db_CLIENTreceive: no entity was found 227 08:31:54.590 [69314] <8> file_to_cache_item: [vnet_addrinfo.c:6555] fopen() failed ERRNO=0 FILE=/usr/openv/var/host_cache/1ff/ffffffff+bpcd,1,1,0,1,0+.txt 08:31:54.640 [69314] <8> file_to_cache_item: [vnet_addrinfo.c:6555] fopen() failed ERRNO=2 FILE=/usr/openv/var/host_cache/0b9/d74f64b9+bpcd,1,20,2,1,0+MASTER_NAME.txt 08:31:54.662 [69314] <2> vnet_pbxConnect: pbxConnectEx Succeeded 08:31:54.662 [69314] <2> logconnections: BPCD CONNECT FROM MEDIA_Y_IP.56196 TO MASTER_IP.1556 fd = 0 08:31:54.663 [69314] <8> file_to_cache_item: [vnet_addrinfo.c:6555] fopen() failed ERRNO=2 FILE=/usr/openv/var/host_cache/08d/b517c48d+veritas_pbx,1,20,2,1,0+MASTER_IP.txt 08:31:54.770 [69314] <8> file_to_cache_item: [vnet_addrinfo.c:6555] fopen() failed ERRNO=2 FILE=/usr/openv/var/host_cache/08d/b517c48d+vnetd,1,20,2,1,0+MASTER_IP.txt 08:31:54.797 [69314] <2> vnet_pbxConnect: pbxConnectEx Succeeded 08:31:54.798 [69314] <8> do_pbx_service: [vnet_connect.c:2108] via PBX VNETD CONNECT FROM MEDIA_Y_IP.24917 TO MASTER_IP.1556 fd = 18 08:31:54.799 [69314] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:443] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa 08:31:54.839 [69314] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:460] ipc_string /usr/openv/var/tmp/vnet-16856560916914833478000000099-eGc8ns 08:31:54.947 [69314] <4> create_tpreq_file: symlink to path /dev/nst14 08:31:54.948 [69314] <2> manage_drive_before_load: SCSI RESERVE 08:31:54.950 [69314] <2> manage_drive_before_load: report_attr, fl1 0x00000001, fl2 0x00000000 08:31:54.950 [69314] <4> expandpath: /usr/openv/netbackup/db/media/tpreq/drive_HP.ULTRIUM6-SCSI.043 08:31:55.480 [69314] <2> tapelib: wait_for_ltid, Mount, timeout 0 08:31:56.097 [69314] <2> Media_signal_poll: 2:Terminate detected (tapelib.c:615) 08:31:56.097 [69314] <2> mount_open_media: mount canceled detected in tpreq(), signo = 1 08:31:56.097 [69314] <2> ConnectionCache::connectAndCache: Acquiring new connection for host MASTER_NAME, query type 1 08:31:56.110 [69360] <2> fill_buffer: [69314] socket is closed, waited for empty buffer 0 times, delayed 0 times, read 0 bytes 08:31:56.111 [69360] <2> Orb::destroyObjectRef: destroying object reference(Orb.cpp:2209) 08:31:56.172 [69314] <2> vnet_pbxConnect: pbxConnectEx Succeeded 08:31:56.173 [69314] <2> logconnections: BPDBM CONNECT FROM MEDIA_Y_IP.62648 TO MASTER_IP.1556 fd = 0 08:31:56.173 [69314] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2 08:31:56.180 [69314] <2> db_end: Need to collect reply 08:31:56.181 [69314] <16> mount_open_media: media manager terminated during mount of media id T10086, possible media mount timeout 08:31:56.181 [69314] <2> Media_dispatch_signal: calling child_wait (bptm.c:10308) delay 0 seconds 08:31:56.181 [69314] <2> Media_dispatch_signal: calling catch_signal for 1 (bptm.c:10308) delay 0 seconds 08:31:58.181 [69314] <2> ConnectionCache::connectAndCache: Acquiring new connection for host MASTER_NAME, query type 1 08:31:58.245 [69314] <2> vnet_pbxConnect: pbxConnectEx Succeeded 08:31:58.246 [69314] <2> logconnections: BPDBM CONNECT FROM MEDIA_Y_IP.43043 TO MASTER_IP.1556 fd = 0 08:31:58.246 [69314] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2 08:31:58.252 [69314] <2> db_end: Need to collect reply 08:31:58.252 [69314] <16> catch_signal: media manager terminated by parent process 08:31:58.253 [69314] <2> drivename_write: Called with mode 1 08:31:58.253 [69314] <2> drivename_unlock: unlocked 08:31:58.253 [69314] <2> drivename_close: Called for file HP.ULTRIUM6-SCSI.043 08:31:58.253 [69314] <2> send_MDS_msg: MEDIA_DONE 0 4025533 0 T10086 4008795 180 {F81E9E86-9246-11E9-8B4F-5B4650151362} 08:31:58.253 [69314] <2> packageBptmResourceDoneMsg: msg (MEDIA_DONE 0 4025533 0 T10086 4008795 180 {F81E9E86-9246-11E9-8B4F-5B4650151362}) 08:31:58.253 [69314] <2> packageBptmResourceDoneMsg: keyword MEDIA_DONE version 0 jobid 4025533 copyNum 0 mediaId T10086 mediaKey 4008795 unloadDelay 180 allocId {F81E9E86-9246-11E9-8B4F-5B4650151362} 08:31:58.253 [69314] <2> packageBptmResourceDoneMsg: returns 0 08:31:58.253 [69314] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:507) 08:31:58.254 [69314] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Reusing endpoint, target: MASTER_IP, source: ANY(Endpoint_Selector.cpp:659) 08:31:58.254 [69314] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure invocation->perform_call(...) to host MASTER_IP SUCCESS(Endpoint_Selector.cpp:1149) 08:31:58.255 [69314] <2> JobInst::sendIrmMsg: returning 08:31:58.255 [69314] <2> catch_signal: EXITING with status 82 08:31:58.756 [69374] <2> bptm: INITIATING (VERBOSE = 3): -unload -dn HP.ULTRIUM6-SCSI.043 -dp /dev/nst14 -dk 2000597 -m T10086 -mk 4008795 -mds 8 -alocid 3243115 -jobid -1558683329 -jm 08:31:58.774 [69374] <8> vnet_same_host_and_update: [vnet_addrinfo.c:2820] name2 is empty 0 0x0 08:31:58.796 [69374] <4> bptm: emmserver_name = MASTER_NAME 08:31:58.796 [69374] <4> bptm: emmserver_port = 1556 08:31:58.807 [69374] <2> Orb::setDebugLevelFromVxul: Orb logging configuration level set to 0(Orb.cpp:2261) 08:31:58.807 [69374] <2> emmlib_initialize: (-) Connection attempt #<0> 08:31:58.807 [69374] <2> emmlib_initializeEx: (-) Connecting to the Server Port <1556>, App OrbOpt 08:31:58.807 [69374] <2> SetMaxFileNum: current max fileno: 4096, max fileno hard limit: 4096(SetMaxFileNum.cpp:34) 08:31:58.807 [69374] <2> SetMaxFileNum: successfully set max fileno: current=4096, max=4096(SetMaxFileNum.cpp:90) 08:31:58.810 [69374] <2> Orb::setDebugLevelFromVxul: Orb logging configuration level set to 0(Orb.cpp:2261) 08:31:58.810 [69374] <2> Orb::init: checking if NBAC is enabled(Orb.cpp:508) 08:31:58.827 [69374] <2> EndpointSelector_R2::updateConfMembers: epsr2: bp.conf changed since last look, reloading(Endpoint_Selector.cpp:384) 08:31:58.827 [69374] <2> EndpointSelectorFactory::EndpointSelectorFactory: Endpoint Selector model: 7.1 Flags: 0(Endpoint_Selector.cpp:1805) 08:31:58.827 [69374] <2> EndpointSelector_R2::updateConfMembers: epsr2: bp.conf changed since last look, reloading(Endpoint_Selector.cpp:384) 08:31:58.828 [69374] <2> InsecureEndpointSelectorFactory::InsecureEndpointSelectorFactory: Endpoint Selector model: 7.1 Flags: 0 [Ignoring BPCONF server list](Endpoint_Selector.cpp:1900) 08:31:58.828 [69374] <2> checkService: found service: UTF16_BOM_Factory(Orb.cpp:484) 08:31:58.828 [69374] <2> checkService: found service: UTF8-Latin1_Factory(Orb.cpp:484) 08:31:58.828 [69374] <2> LogMaxFileNum: current max fileno: 4096, max fileno hard limit: 4096(SetMaxFileNum.cpp:113) 08:31:58.828 [69374] <2> Orb::getConfig: found service entry: name = veritas_pbx port = 1556 proto = tcp(Orb.cpp:402) 08:31:58.828 [69374] <2> Orb::getConfig: cluster_name and required_interface not defined, using ANY(Orb.cpp:454) 08:31:58.828 [69374] <2> Orb::init: Enabling ORBNativeCharCodeSet UTF-8(Orb.cpp:594) 08:31:58.828 [69374] <2> Orb::init: initializing ORB with: bptm -ORBSvcConfDirective "-ORBDottedDecimalAddresses 0" -ORBSvcConfDirective "static Resource_Factory '-ORBNativeCharCodeSet UTF-8'" -ORBSvcConfDirective "static PBXIOP_Factory '-enable_keepalive'" -ORBSvcConfDirective "static EndpointSelectorFactory ''" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory PBXIOP_Factory'" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory IIOP_Factory'" -ORBDefaultInitRef '' -ORBSvcConfDirective "static PBXIOP_Evaluator_Factory '-orb EMMlib_Orb'"(Orb.cpp:640) 08:31:58.828 [69374] <2> Orb::init: added args has value of: (Orb.cpp:770) 08:31:58.829 [69374] <2> Orb::init: initializing ORB EMMlib_Orb with: bptm -ORBSvcConfDirective "-ORBDottedDecimalAddresses 0" -ORBSvcConfDirective "static Resource_Factory '-ORBNativeCharCodeSet UTF-8'" -ORBSvcConfDirective "static PBXIOP_Factory '-enable_keepalive'" -ORBSvcConfDirective "static EndpointSelectorFactory ''" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory PBXIOP_Factory'" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory IIOP_Factory'" -ORBDefaultInitRef '' -ORBSvcConfDirective "static PBXIOP_Evaluator_Factory '-orb EMMlib_Orb'" -ORBSvcConfDirective "static Resource_Factory '-ORBConnectionCacheMax 1024 '" -ORBSvcConf /dev/null -ORBSvcConfDirective "static Server_Strategy_Factory '-ORBMaxRecvGIOPPayloadSize 268435456'"(Orb.cpp:825) 08:31:58.831 [69374] <2> NBOrbInitializer::post_init: Adding interceptors(NBOrbInitializer.cpp:74) 08:31:58.831 [69374] <2> Orb::init: caching EndpointSelectorFactory(Orb.cpp:840) 08:31:58.831 [69374] <2> Orb::setOrbTimeoutPolicy: setting ORB connection timeout policy: tv = 30000(Orb.cpp:1504) 08:31:58.831 [69374] <2> Orb::activate: Client Orb called activate; Ignored(Orb.cpp:1236) 08:31:58.832 [69374] <2> Orb::setOrbTimeoutPolicy: setting ORB connection timeout policy: tv = 60(Orb.cpp:1504) 08:31:58.832 [69374] <2> Orb::setOrbConnectTimeout: timeout seconds: 60(Orb.cpp:1481) 08:31:58.832 [69374] <2> emmlib_initializeEx: (-) Setting connect timeout to <60> sec 08:31:58.832 [69374] <2> Orb::setOrbTimeoutPolicy: setting ORB request timeout policy: tv = 1800(Orb.cpp:1504) 08:31:58.832 [69374] <2> Orb::setOrbRequestTimeout: timeout seconds: 1800(Orb.cpp:1490) 08:31:58.832 [69374] <2> emmlib_initializeEx: (-) Setting request timeout to <1800> sec 08:31:58.832 [69374] <2> Orb::connectToObject: Connection attempt #1 mW:60 rW:0(Orb.cpp:1167) 08:31:58.832 [69374] <2> Orb::createInsecureObjectRef: corbaloc being used is: corbaloc:pbxiop:MASTER_NAME:1556:EMM/EMMServer(Orb.cpp:1440) 08:31:58.832 [69374] <2> Orb::connectToObjectOnce: attempt to create insecure connection(Orb.cpp:1034) 08:31:58.833 [69374] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:507) 08:31:58.854 [69374] <2> EndpointSelector_R2::cachedHostLookup: epsr2: Hostname MASTER_NAME has 1 candidate addresses(Endpoint_Selector.cpp:1605) 08:31:58.854 [69374] <2> EndpointSelector_R2::performCallUsingEndpointAndSourceBinding: epsr2: Target: MASTER_IP, source: ANY(Endpoint_Selector.cpp:939) 08:31:58.856 [69374] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure invocation->perform_call(...) to host MASTER_IP SUCCESS(Endpoint_Selector.cpp:1149) 08:31:58.860 [69374] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:507) 08:31:58.860 [69374] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Reusing endpoint, target: MASTER_IP, source: ANY(Endpoint_Selector.cpp:659) 08:31:58.860 [69374] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure invocation->perform_call(...) to host MASTER_IP SUCCESS(Endpoint_Selector.cpp:1149) 08:31:58.861 [69374] <2> Orb::connectToObjectOnce: connection created(Orb.cpp:1052) 08:31:58.862 [69374] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:507) 08:31:58.862 [69374] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Reusing endpoint, target: MASTER_IP, source: ANY(Endpoint_Selector.cpp:659) 08:31:58.862 [69374] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure invocation->perform_call(...) to host MASTER_IP SUCCESS(Endpoint_Selector.cpp:1149) 08:31:58.863 [69374] <4> CreateConnectionID: (-) Using existing uninitialized Connection ID 0 08:31:58.863 [69374] <4> emmlib_initializeEx: (0) Successfully initialized EMM session 08:31:58.863 [69374] <2> send_brm_msg: PID of bpxm = 69374 08:31:58.863 [69374] <2> nbjm_media_request: Passing job control to NBJM, type UNLOAD/6 08:31:58.863 [69374] <2> nbjm_media_request: old_media_id = NULL, media_id = T10086 08:31:58.864 [69374] <2> SetMaxFileNum: current max fileno: 4096, max fileno hard limit: 4096(SetMaxFileNum.cpp:34) 08:31:58.864 [69374] <2> SetMaxFileNum: successfully set max fileno: current=4096, max=4096(SetMaxFileNum.cpp:90) 08:31:58.864 [69374] <2> Orb::init: checking if NBAC is enabled(Orb.cpp:508) 08:31:58.864 [69374] <2> checkService: found service: UTF16_BOM_Factory(Orb.cpp:484) 08:31:58.864 [69374] <2> checkService: found service: UTF8-Latin1_Factory(Orb.cpp:484) 08:31:58.864 [69374] <2> LogMaxFileNum: current max fileno: 4096, max fileno hard limit: 4096(SetMaxFileNum.cpp:113) 08:31:58.864 [69374] <2> Orb::getConfig: found service entry: name = veritas_pbx port = 1556 proto = tcp(Orb.cpp:402) 08:31:58.864 [69374] <2> Orb::getConfig: cluster_name and required_interface not defined, using ANY(Orb.cpp:454) 08:31:58.864 [69374] <2> Orb::init: Enabling ORBNativeCharCodeSet UTF-8(Orb.cpp:594) 08:31:58.864 [69374] <2> Orb::init: substituting orb name of: Default_DAEMON_Orb(Orb.cpp:621) 08:31:58.864 [69374] <2> Orb::init: initializing ORB with: Unknown -ORBSvcConfDirective "-ORBDottedDecimalAddresses 0" -ORBSvcConfDirective "static Resource_Factory '-ORBNativeCharCodeSet UTF-8'" -ORBSvcConfDirective "static PBXIOP_Factory '-enable_keepalive'" -ORBSvcConfDirective "static EndpointSelectorFactory ''" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory PBXIOP_Factory'" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory IIOP_Factory'" -ORBDefaultInitRef '' -ORBSvcConfDirective "static PBXIOP_Evaluator_Factory '-orb Default_DAEMON_Orb'"(Orb.cpp:640) 08:31:58.864 [69374] <2> Orb::init: Created anon service name: NB_69374_388609136(Orb.cpp:697) 08:31:58.864 [69374] <2> Orb::init: endpointvalue is : pbxiop://1556:NB_69374_388609136(Orb.cpp:714) 08:31:58.864 [69374] <2> Orb::init: added args has value of: (Orb.cpp:770) 08:31:58.865 [69374] <2> Orb::init: initializing ORB Default_DAEMON_Orb with: Unknown -ORBSvcConfDirective "-ORBDottedDecimalAddresses 0" -ORBSvcConfDirective "static Resource_Factory '-ORBNativeCharCodeSet UTF-8'" -ORBSvcConfDirective "static PBXIOP_Factory '-enable_keepalive'" -ORBSvcConfDirective "static EndpointSelectorFactory ''" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory PBXIOP_Factory'" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory IIOP_Factory'" -ORBDefaultInitRef '' -ORBSvcConfDirective "static PBXIOP_Evaluator_Factory '-orb Default_DAEMON_Orb'" -ORBSvcConfDirective "static Resource_Factory '-ORBConnectionCacheMax 1024 '" -ORBEndpoint pbxiop://1556:NB_69374_388609136 -ORBSvcConf /dev/null -ORBSvcConfDirective "static Server_Strategy_Factory '-ORBMaxRecvGIOPPayloadSize 268435456'"(Orb.cpp:825) 08:31:58.865 [69374] <2> NBOrbInitializer::post_init: Adding interceptors(NBOrbInitializer.cpp:74) 08:31:58.865 [69374] <2> Orb::init: caching EndpointSelectorFactory(Orb.cpp:840) 08:31:58.865 [69374] <2> Orb::setOrbTimeoutPolicy: setting ORB connection timeout policy: tv = 30000(Orb.cpp:1504) 08:31:58.914 [69374] <8> copy_addrinfo: [vnet_addrinfo.c:3692] no valid addresses to copy 0 0x0 08:31:58.914 [69374] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1636] copy_addrinfo() failed NAME=fe80::da9d:67ff:fe77:62cd%9 STAT=6 08:31:58.914 [69374] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1273] vnet_cached_getaddrinfo_and_update() failed 6 0x6 08:31:58.914 [69374] <2> ExternalResolver::set: Could not resolve host_name: fe80::da9d:67ff:fe77:62cd%9 status/errval: 6(0)(ExternalResolver.cpp:41) 08:31:58.915 [69374] <8> file_to_cache_item: [vnet_addrinfo.c:6555] fopen() failed ERRNO=0 FILE=/usr/openv/var/host_cache/19c/7ecd659c+0,1,422,0,1,0+fe80::21b:21ff:febb:b7b5%10.txt 08:31:58.920 [69374] <8> copy_addrinfo: [vnet_addrinfo.c:3692] no valid addresses to copy 0 0x0 08:31:58.920 [69374] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1636] copy_addrinfo() failed NAME=fe80::21b:21ff:febb:b7b5%10 STAT=6 08:31:58.920 [69374] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1273] vnet_cached_getaddrinfo_and_update() failed 6 0x6 08:31:58.920 [69374] <2> ExternalResolver::set: Could not resolve host_name: fe80::21b:21ff:febb:b7b5%10 status/errval: 6(0)(ExternalResolver.cpp:41) 08:31:58.934 [69374] <2> NBIORInterceptor::establish_components: Encoding IP Address [MEDIA_Y_IP] in IOR(NBIORInterceptor.cpp:117) 08:31:58.934 [69374] <2> Orb::connectToObject: Connection attempt #1 mW:30 rW:0(Orb.cpp:1167) 08:31:58.934 [69374] <2> Orb::createInsecureObjectRef: corbaloc being used is: corbaloc:pbxiop:MASTER_NAME:1556:nbjm/JOBINST(Orb.cpp:1440) 08:31:58.934 [69374] <2> Orb::connectToObjectOnce: attempt to create insecure connection(Orb.cpp:1034) 08:31:58.934 [69374] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:507) 08:31:58.954 [69374] <2> EndpointSelector_R2::cachedHostLookup: epsr2: Hostname MASTER_NAME has 1 candidate addresses(Endpoint_Selector.cpp:1605) 08:31:58.954 [69374] <2> EndpointSelector_R2::performCallUsingEndpointAndSourceBinding: epsr2: Host resolved to GOOD address, target: MASTER_IP, source: ANY(Endpoint_Selector.cpp:903) 08:31:58.955 [69374] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure invocation->perform_call(...) to host MASTER_IP SUCCESS(Endpoint_Selector.cpp:1149) 08:31:58.959 [69374] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:507) 08:31:58.959 [69374] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Reusing endpoint, target: MASTER_IP, source: ANY(Endpoint_Selector.cpp:659) 08:31:58.960 [69374] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure invocation->perform_call(...) to host MASTER_IP SUCCESS(Endpoint_Selector.cpp:1149) 08:31:58.961 [69374] <2> Orb::connectToObjectOnce: connection created(Orb.cpp:1052) 08:31:58.961 [69374] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:507) 08:31:58.961 [69374] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Reusing endpoint, target: MASTER_IP, source: ANY(Endpoint_Selector.cpp:659) 08:31:58.961 [69374] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure invocation->perform_call(...) to host MASTER_IP SUCCESS(Endpoint_Selector.cpp:1149) 08:31:58.962 [69374] <2> packageInitialResourceRequest: started 08:31:58.963 [69374] <2> packageInitialResourceRequest: retVal = 0 08:31:58.963 [69374] <2> Orb::createObjectRef: creating object reference(Orb.cpp:2171) 08:31:58.963 [69374] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:507) 08:31:58.964 [69374] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Reusing endpoint, target: MASTER_IP, source: ANY(Endpoint_Selector.cpp:659) 08:31:58.964 [69374] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure invocation->perform_call(...) to host MASTER_IP SUCCESS(Endpoint_Selector.cpp:1149) 08:31:58.966 [69374] <2> RequestInitialResources: BpxmiV->requestResourcesWithTimeout returns 0 08:31:58.966 [69374] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:507) 08:31:58.966 [69374] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Reusing endpoint, target: MASTER_IP, source: ANY(Endpoint_Selector.cpp:659) 08:31:58.966 [69374] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure invocation->perform_call(...) to host MASTER_IP SUCCESS(Endpoint_Selector.cpp:1149) 08:31:58.968 [69374] <2> InsecureServerEvaluator::allow_peer: Allowing peer to connect(SecureEvaluator.cpp:403) 08:31:58.969 [69374] <2> resourcesAvailable: m_allocationSeq.length() == 1 08:31:58.969 [69374] <2> resourcesAvailable: thisAllocation->mdar.Bsr.BptmStrings.length() == 7 08:31:58.969 [69374] <2> resourcesAvailable: MEDIADB 1 3243115 T10086 4008795 ------ 20 1560916911 0 0 0 0 0 0 24 11 0 0 0 0 0 0 08:31:58.969 [69374] <2> resourcesAvailable: VOLUME 1 T10086 4008795 T10086L6 Mediation-Archive QUANTUM 0170420231 24 8 2 1147 0 {00000000-0000-0000-0000-000000000000} 0 08:31:58.969 [69374] <2> resourcesAvailable: DRIVE 3 HP.ULTRIUM6-SCSI.043 2000597 F00286F085 /dev/nst14 -1 -1 -1 -1 -1 -1 -1 -1 *NULL* *NULL* *NULL* *NULL* 1 0 0 1 0 0 08:31:58.969 [69374] <2> resourcesAvailable: STORAGE 1 MEDIA_Y-hcart3-Qi6K-tld-2 20 1048576 2 1 0 0 MEDIA_Y MEDIA_Y *NULL* 08:31:58.969 [69374] <2> resourcesAvailable: DISKGROUP 0 6 *NULL* 6 *NULL* 6 *NULL* 08:31:58.969 [69374] <2> resourcesAvailable: DISKVOLUME 0 6 *NULL* 6 *NULL* 0 08:31:58.969 [69374] <2> resourcesAvailable: DISKMOUNTPOINT 0 6 *NULL* 08:31:58.969 [69374] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:507) 08:31:58.969 [69374] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Reusing endpoint, target: MASTER_IP, source: ANY(Endpoint_Selector.cpp:659) 08:31:58.970 [69374] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure invocation->perform_call(...) to host MASTER_IP SUCCESS(Endpoint_Selector.cpp:1149) 08:31:58.971 [69374] <2> packageInitialResourceRequestResult: retVal = 0 08:31:58.971 [69374] <2> RequestInitialResources: retVal = 0 emmStatus = 0 08:31:58.971 [69374] <2> RequestInitialResources: returning 08:31:58.971 [69374] <2> populateBptmOpaqueStrings: packaging bptm string [MEDIADB 1 3243115 T10086 4008795 ------ 20 1560916911 0 0 0 0 0 0 24 11 0 0 0 0 0 0], length 83 08:31:58.971 [69374] <2> populateBptmOpaqueStrings: packaging bptm string [VOLUME 1 T10086 4008795 T10086L6 Mediation-Archive QUANTUM 0170420231 24 8 2 1147 0 {00000000-0000-0000-0000-000000000000} 0], length 124 08:31:58.971 [69374] <2> populateBptmOpaqueStrings: packaging bptm string [DRIVE 3 HP.ULTRIUM6-SCSI.043 2000597 F00286F085 /dev/nst14 -1 -1 -1 -1 -1 -1 -1 -1 *NULL* *NULL* *NULL* *NULL* 1 0 0 1 0 0], length 122 08:31:58.971 [69374] <2> populateBptmOpaqueStrings: packaging bptm string [STORAGE 1 MEDIA_Y-hcart3-Qi6K-tld-2 20 1048576 2 1 0 0 MEDIA_Y MEDIA_Y *NULL*], length 95 08:31:58.971 [69374] <2> populateBptmOpaqueStrings: packaging bptm string [DISKGROUP 0 6 *NULL* 6 *NULL* 6 *NULL*], length 38 08:31:58.971 [69374] <2> populateBptmOpaqueStrings: packaging bptm string [DISKVOLUME 0 6 *NULL* 6 *NULL* 0], length 32 08:31:58.971 [69374] <2> populateBptmOpaqueStrings: packaging bptm string [DISKMOUNTPOINT 0 6 *NULL*], length 25 08:31:58.971 [69374] <2> parse_resource_strings: MEDIADB 1 3243115 T10086 4008795 ------ 20 1560916911 0 0 0 0 0 0 24 11 0 0 0 0 0 0 08:31:58.971 [69374] <2> parse_resource_strings: Parsed message type 15, version 1, 21 parameters 08:31:58.971 [69374] <2> parse_resource_strings: VOLUME 1 T10086 4008795 T10086L6 Mediation-Archive QUANTUM 0170420231 24 8 2 1147 0 {00000000-0000-0000-0000-000000000000} 0 08:31:58.971 [69374] <2> parse_resource_strings: Parsed message type 16, version 1, 14 parameters 08:31:58.971 [69374] <2> parse_resource_strings: DRIVE 3 HP.ULTRIUM6-SCSI.043 2000597 F00286F085 /dev/nst14 -1 -1 -1 -1 -1 -1 -1 -1 *NULL* *NULL* *NULL* *NULL* 1 0 0 1 0 0 08:31:58.971 [69374] <2> parse_resource_strings: Parsed message type 17, version 3, 23 parameters 08:31:58.971 [69374] <2> parse_resource_strings: STORAGE 1 MEDIA_Y-hcart3-Qi6K-tld-2 20 1048576 2 1 0 0 MEDIA_Y MEDIA_Y *NULL* 08:31:58.971 [69374] <2> parse_resource_strings: Parsed message type 18, version 1, 11 parameters 08:31:58.971 [69374] <2> parse_resource_strings: DISKGROUP 0 6 *NULL* 6 *NULL* 6 *NULL* 08:31:58.971 [69374] <2> parse_resource_strings: Parsed message type 21, version 0, 7 parameters 08:31:58.971 [69374] <2> parse_resource_strings: DISKVOLUME 0 6 *NULL* 6 *NULL* 0 08:31:58.971 [69374] <2> parse_resource_strings: Parsed message type 22, version 0, 6 parameters 08:31:58.971 [69374] <2> parse_resource_strings: DISKMOUNTPOINT 0 6 *NULL* 08:31:58.971 [69374] <2> parse_resource_strings: Parsed message type 23, version 0, 3 parameters 08:31:58.971 [69374] <2> nbjm_media_request: Job control returned to BPTM 08:31:58.971 [69374] <2> drivename_open: Called with Create 1, file HP.ULTRIUM6-SCSI.043 08:31:58.971 [69374] <2> drivename_lock: lock established 08:31:58.971 [69374] <4> create_tpreq_file: symlink to path /dev/nst14 08:31:58.972 [69374] <2> drivename_write: Called with mode 2 08:31:58.973 [69374] <2> process_tapealert: TapeAlert returned 0x00000000 0x00000000 (from tapealert_and_release) 08:31:58.973 [69374] <2> really_tpunmount: tpunmount'ing /usr/openv/netbackup/db/media/tpreq/drive_HP.ULTRIUM6-SCSI.043 08:31:59.481 [69374] <4> really_tpunmount: retry unload after delay 08:32:59.481 [69374] <2> really_tpunmount: tpunmount'ing /usr/openv/netbackup/db/media/tpreq/drive_HP.ULTRIUM6-SCSI.043 08:32:59.598 [69374] <2> tapelib: wait_for_ltid, UnMount, timeout 2400 08:33:41.172 [69374] <2> ConnectionCache::connectAndCache: Acquiring new connection for host MASTER_NAME, query type 1 08:33:41.242 [69374] <2> vnet_pbxConnect: pbxConnectEx Succeeded 08:33:41.243 [69374] <2> logconnections: BPDBM CONNECT FROM MEDIA_Y_IP.46973 TO MASTER_IP.1556 fd = 17 08:33:41.243 [69374] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2 08:33:41.250 [69374] <2> db_end: Need to collect reply 08:33:41.250 [69374] <4> report_resource_done: VBRD 1 69374 0 HP.ULTRIUM6-SCSI.043 T10086 08:33:41.250 [69374] <4> create_tpreq_file: symlink to path /dev/nst14 08:33:41.251 [69374] <2> process_tapealert: TapeAlert returned 0x00000000 0x00000000 (from tapealert_and_release) 08:33:41.253 [69374] <2> tapealert_and_release: report_attr, fl1 0x00000001, fl2 0x00000000 08:33:41.253 [69374] <2> tapealert_and_release: SCSI RELEASE 08:33:41.253 [69374] <2> drivename_unlock: unlocked 08:33:41.253 [69374] <2> drivename_close: Called for file HP.ULTRIUM6-SCSI.043 08:33:41.254 [69374] <2> drivename_remove: Called 08:33:41.254 [69374] <2> main: Sending [EXIT STATUS 0] to NBJM 08:33:41.254 [69374] <2> bptm: EXITING with status 0 <---------- ******************************************************************************************************************************************************************************************************** admin ******************************************************************************************************************************************************************************************************** 08:03:24.440 [1237] <2> logparams: /usr/openv/netbackup/bin/admincmd/bpgetconfig 08:03:24.441 [1237] <2> bpgetconfig: VERBOSE = 0 08:03:24.447 [1237] <8> vnet_get_user_credential_path: [vnet_vxss.c:1474] status 35 0x23 08:03:24.447 [1237] <8> vnet_check_user_certificate: [vnet_vxss_helper.c:3643] vnet_get_user_credential_path failed 35 0x23 08:18:38.209 [13473] <2> bpgetconfig: INITIATING: version NetBackup 7.7.2 created: 2016011116 08:18:38.209 [13473] <2> logparams: /usr/openv/netbackup/bin/admincmd/bpgetconfig 08:18:38.209 [13473] <2> bpgetconfig: VERBOSE = 0 08:18:38.218 [13473] <8> vnet_get_user_credential_path: [vnet_vxss.c:1474] status 35 0x23 08:18:38.218 [13473] <8> vnet_check_user_certificate: [vnet_vxss_helper.c:3643] vnet_get_user_credential_path failed 35 0x23 08:27:59.249 [29278] <2> nbemmcmd: VERBOSE = 0 08:27:59.253 [29278] <8> vnet_get_user_credential_path: [vnet_vxss.c:1474] status 35 0x23 08:27:59.253 [29278] <8> vnet_check_user_certificate: [vnet_vxss_helper.c:3643] vnet_get_user_credential_path failed 35 0x23 08:27:59.253 [29278] <2> : NBEMMCMD -listsettings -brief -emmname MASTER_SERVER-emmport 1556 -machinename MEDIA_Y 08:27:59.291 [29278] <2> Orb::init: Enabling ORBNativeCharCodeSet UTF-8(Orb.cpp:801) 08:27:59.292 [29278] <2> Orb::init: initializing ORB EMMlib_Orb with: nbemmcmd -ORBSvcConfDirective "-ORBDottedDecimalAddresses 0" -ORBSvcConfDirective "static Resource_Factory '-ORBNativeCharCodeSet UTF-8'" -ORBSvcConfDirective "static PBXIOP_Factory '-enable_keepalive'" -ORBSvcConfDirective "static EndpointSelectorFactory ''" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory PBXIOP_Factory'" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory IIOP_Factory'" -ORBDefaultInitRef '' -ORBSvcConfDirective "static PBXIOP_Evaluator_Factory '-orb EMMlib_Orb'" -ORBSvcConfDirective "static Resource_Factory '-ORBConnectionCacheMax 1024 '" -ORBSvcConf /dev/null -ORBSvcConfDirective "static Server_Strategy_Factory '-ORBMaxRecvGIOPPayloadSize 268435456'"(Orb.cpp:1015) 08:27:59.293 [29278] <2> Orb::init: caching EndpointSelectorFactory(Orb.cpp:1029) 08:27:59.293 [29278] <2> Orb::setOrbConnectTimeout: timeout seconds: 60(Orb.cpp:1380) 08:27:59.294 [29278] <2> Orb::setOrbRequestTimeout: timeout seconds: 1800(Orb.cpp:1389) 08:28:47.022 [803] <2> nbemmcmd: VERBOSE = 0 08:28:47.025 [803] <8> vnet_get_user_credential_path: [vnet_vxss.c:1474] status 35 0x23 08:28:47.025 [803] <8> vnet_check_user_certificate: [vnet_vxss_helper.c:3643] vnet_get_user_credential_path failed 35 0x23 08:28:47.026 [803] <2> : NBEMMCMD -listsettings -brief -emmname MASTER_SERVER-emmport 1556 -machinename MEDIA_Y 08:28:47.064 [803] <2> Orb::init: Enabling ORBNativeCharCodeSet UTF-8(Orb.cpp:801) 08:28:47.065 [803] <2> Orb::init: initializing ORB EMMlib_Orb with: nbemmcmd -ORBSvcConfDirective "-ORBDottedDecimalAddresses 0" -ORBSvcConfDirective "static Resource_Factory '-ORBNativeCharCodeSet UTF-8'" -ORBSvcConfDirective "static PBXIOP_Factory '-enable_keepalive'" -ORBSvcConfDirective "static EndpointSelectorFactory ''" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory PBXIOP_Factory'" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory IIOP_Factory'" -ORBDefaultInitRef '' -ORBSvcConfDirective "static PBXIOP_Evaluator_Factory '-orb EMMlib_Orb'" -ORBSvcConfDirective "static Resource_Factory '-ORBConnectionCacheMax 1024 '" -ORBSvcConf /dev/null -ORBSvcConfDirective "static Server_Strategy_Factory '-ORBMaxRecvGIOPPayloadSize 268435456'"(Orb.cpp:1015) 08:28:47.066 [803] <2> Orb::init: caching EndpointSelectorFactory(Orb.cpp:1029) 08:28:47.067 [803] <2> Orb::setOrbConnectTimeout: timeout seconds: 60(Orb.cpp:1380) 08:28:47.067 [803] <2> Orb::setOrbRequestTimeout: timeout seconds: 1800(Orb.cpp:1389) 08:31:21.194 [14057] <2> bpconfig: INITIATING: version NetBackup 7.7.2 created: 2016011116 08:31:21.194 [14057] <2> logparams: -M MASTER_SERVER-l 08:31:21.194 [14057] <2> bpconfig: VERBOSE = 0 08:31:21.195 [14057] <2> bpconfig: EXIT status = 0 08:31:21.467 [14062] <2> nbsvrgrp: VERBOSE = 0 08:31:21.467 [14062] <2> nbsvrgrp: INITIATING: NetBackup 7.7.2 created: 2016011116 08:31:21.467 [14062] <2> logparams: -list -noverbose -M MASTER_SERVER-grptype MediaSharing 08:31:21.467 [14062] <4> nbsvrgrp: emmserver_name = mstr-nbkp-srv 08:31:21.467 [14062] <4> nbsvrgrp: emmserver_port = 1556 08:31:21.500 [14062] <2> Orb::init: Enabling ORBNativeCharCodeSet UTF-8(Orb.cpp:801) 08:31:21.501 [14062] <2> Orb::init: initializing ORB EMMlib_Orb with: nbsvrgrp -ORBSvcConfDirective "-ORBDottedDecimalAddresses 0" -ORBSvcConfDirective "static Resource_Factory '-ORBNativeCharCodeSet UTF-8'" -ORBSvcConfDirective "static PBXIOP_Factory '-enable_keepalive'" -ORBSvcConfDirective "static EndpointSelectorFactory ''" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory PBXIOP_Factory'" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory IIOP_Factory'" -ORBDefaultInitRef '' -ORBSvcConfDirective "static PBXIOP_Evaluator_Factory '-orb EMMlib_Orb'" -ORBSvcConfDirective "static Resource_Factory '-ORBConnectionCacheMax 1024 '" -ORBSvcConf /dev/null -ORBSvcConfDirective "static Server_Strategy_Factory '-ORBMaxRecvGIOPPayloadSize 268435456'"(Orb.cpp:1015) 08:31:21.503 [14062] <2> Orb::init: caching EndpointSelectorFactory(Orb.cpp:1029) 08:31:21.503 [14062] <2> Orb::setOrbConnectTimeout: timeout seconds: 60(Orb.cpp:1380) 08:31:21.503 [14062] <2> Orb::setOrbRequestTimeout: timeout seconds: 1800(Orb.cpp:1389) 08:31:21.509 [14062] <2> nbsvrgrp: Quering for multiple server groups, Query Type 3 08:31:21.509 [14062] <2> nbsvrgrp: Setting ServerGroupType Filter 08:31:21.509 [14062] <2> nbsvrgrp: Calling emmlib_QueryServerGroupsInMaster 08:31:21.518 [14062] <4> nbsvrgrp: EMM returned EMM_ERROR_SQLNoDataFound, continuing 08:31:21.519 [14062] <16> nbsvrgrp: no entity was found 08:31:50.835 [16458] <2> bpduplicate: VERBOSE = 0 08:31:50.837 [16473] <2> bpduplicate: INITIATING (VERBOSE = 0): NetBackup 7.7.2 created: 2016011116 08:31:50.838 [16473] <2> logparams: -X -s 992775335 -e 1560886199 -primary -class POLICY_NAME -M MASTER_SERVER-number_copies 1 -dstunit MEDIA_Y-hcart3-Qi6K-tld-2 -dp DISK_POOL -owner *ANY* -fail_on_error 0 -rl -1 -L /usr/openv/netbackup/logs/user_ops/root/logs/jbpDuplicate-20190619083149.log -Bidfile /usr/openv/netbackup/logs/user_ops/root/logs/jbp-27739560916910076211000000362-A2V2Zw -fork 08:31:50.863 [16473] <8> vnet_get_user_credential_path: [vnet_vxss.c:1474] status 35 0x23 08:31:50.863 [16473] <8> vnet_check_user_certificate: [vnet_vxss_helper.c:3643] vnet_get_user_credential_path failed 35 0x23 08:31:50.863 [16473] <2> ConnectionCache::connectAndCache: Acquiring new connection for host mstr-nbkp-srv, query type 260 08:31:50.864 [16473] <2> logconnections: BPDBM CONNECT FROM 127.0.0.1.35080 TO 127.0.0.1.13721 fd = 1 08:31:50.867 [16473] <2> db_end: Need to collect reply 08:31:50.893 [16473] <2> job_connect: SO_KEEPALIVE set on socket 1 for client mstr-nbkp-srv 08:31:50.893 [16473] <2> logconnections: BPJOBD CONNECT FROM 127.0.0.1.45508 TO 127.0.0.1.13723 fd = 1 08:31:50.893 [16473] <2> job_authenticate_connection: VxSS authentication check for now... 08:31:50.893 [16473] <8> vnet_get_user_credential_path: [vnet_vxss.c:1474] status 35 0x23 08:31:50.893 [16473] <8> vnet_check_user_certificate: [vnet_vxss_helper.c:3643] vnet_get_user_credential_path failed 35 0x23 08:31:50.895 [16473] <2> job_connect: Connected to the host MASTER_SERVERcontype 53 jobid <0> socket <1> 08:31:50.895 [16473] <2> job_connect: Connected on port 45508 08:31:50.944 [16473] <2> LOCAL CLASS_ATT_DEFS: Product ID = 6 08:31:50.952 [16473] <2> bpduplicate: Duplicate started 06/19/19 08:31:50 08:31:50.952 [16473] <2> logconnections: BPCD CONNECT FROM 127.0.0.1.59106 TO 127.0.0.1.13782 fd = 2 08:31:50.957 [16473] <2> bpcr_get_version_rqst: bpcd version: 07720000 08:31:50.958 [16473] <2> open_progress_log: opening progress log(s) 08:31:50.958 [16473] <2> bpduplicate: Activity monitor job id = 4025533 08:31:50.959 [16473] <4> db_getSTUNIT: emmserver_name = mstr-nbkp-srv 08:31:50.959 [16473] <4> db_getSTUNIT: emmserver_port = 1556 08:31:50.991 [16473] <2> Orb::init: Enabling ORBNativeCharCodeSet UTF-8(Orb.cpp:801) 08:31:50.992 [16473] <2> Orb::init: initializing ORB EMMlib_Orb with: db_getSTUNIT -ORBSvcConfDirective "-ORBDottedDecimalAddresses 0" -ORBSvcConfDirective "static Resource_Factory '-ORBNativeCharCodeSet UTF-8'" -ORBSvcConfDirective "static PBXIOP_Factory '-enable_keepalive'" -ORBSvcConfDirective "static EndpointSelectorFactory ''" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory PBXIOP_Factory'" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory IIOP_Factory'" -ORBDefaultInitRef '' -ORBSvcConfDirective "static PBXIOP_Evaluator_Factory '-orb EMMlib_Orb'" -ORBSvcConfDirective "static Resource_Factory '-ORBConnectionCacheMax 1024 '" -ORBSvcConf /dev/null -ORBSvcConfDirective "static Server_Strategy_Factory '-ORBMaxRecvGIOPPayloadSize 268435456'"(Orb.cpp:1015) 08:31:50.995 [16473] <2> Orb::init: caching EndpointSelectorFactory(Orb.cpp:1029) 08:31:50.995 [16473] <2> Orb::setOrbConnectTimeout: timeout seconds: 60(Orb.cpp:1380) 08:31:50.995 [16473] <2> Orb::setOrbRequestTimeout: timeout seconds: 1800(Orb.cpp:1389) 08:31:51.016 [16473] <8> vnet_get_user_credential_path: [vnet_vxss.c:1474] status 35 0x23 08:31:51.016 [16473] <8> vnet_check_user_certificate: [vnet_vxss_helper.c:3643] vnet_get_user_credential_path failed 35 0x23 08:31:51.016 [16473] <2> ConnectionCache::connectAndCache: Acquiring new connection for host mstr-nbkp-srv, query type 81 08:31:51.017 [16473] <2> logconnections: BPDBM CONNECT FROM 127.0.0.1.35533 TO 127.0.0.1.13721 fd = 10 08:31:51.129 [16473] <2> add_to_worklist: source blknum:0 ,starting blknum:0 for backup id MEDIA_X_1443523049 08:31:51.130 [16473] <2> add_to_worklist: TotalKbytes = 3107149344 for backup id MEDIA_X_1443523049 08:31:51.139 [16473] <2> db_end: Need to collect reply 08:31:51.162 [16473] <2> check_optimization: Checking to see if an optimized duplication or NDMP Direct Copy can be performed 08:31:51.163 [16473] <2> dsmlibp_emmserver: dsmlib:dsmlib_common.cpp:dsmlibp_emmserver:166 <4> EMM server set to mstr-nbkp-srv 08:31:51.163 [16473] <2> dsmlibp_masterserver: dsmlib:dsmlib_common.cpp:dsmlibp_masterserver:189 <4> Master server set to mstr-nbkp-srv 08:31:51.163 [16473] <2> dsmlibp_connect_dsm: dsmlib:dsmlib_common.cpp:dsmlibp_connect_dsm:271 <4> Connecting to DiskServiceMgr on mstr-nbkp-srv 08:31:51.163 [16473] <2> dsmlibp_get_orb: dsmlib:dsmlib_common.cpp:dsmlibp_get_orb:59 <4> Using dsmlib private orb 08:31:51.163 [16473] <2> Orb::init: Enabling ORBNativeCharCodeSet UTF-8(Orb.cpp:801) 08:31:51.163 [16473] <2> Orb::init: initializing ORB Default_CLIENT_Orb with: Unknown -ORBSvcConfDirective "-ORBDottedDecimalAddresses 0" -ORBSvcConfDirective "static Resource_Factory '-ORBNativeCharCodeSet UTF-8'" -ORBSvcConfDirective "static PBXIOP_Factory '-enable_keepalive'" -ORBSvcConfDirective "static EndpointSelectorFactory ''" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory PBXIOP_Factory'" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory IIOP_Factory'" -ORBDefaultInitRef '' -ORBSvcConfDirective "static PBXIOP_Evaluator_Factory '-orb Default_CLIENT_Orb'" -ORBSvcConfDirective "static Resource_Factory '-ORBConnectionCacheMax 1024 '" -ORBSvcConf /dev/null -ORBSvcConfDirective "static Server_Strategy_Factory '-ORBMaxRecvGIOPPayloadSize 268435456'"(Orb.cpp:1015) 08:31:51.164 [16473] <2> Orb::init: caching EndpointSelectorFactory(Orb.cpp:1029) 08:31:51.164 [16473] <2> dsmlibp_connect_dsm: dsmlib:dsmlib_common.cpp:dsmlibp_connect_dsm:279 <4> Connecting to NBDSMFSM using orb 0x158cb000 08:31:51.168 [16473] <2> dsmlibp_register_factories: dsmlib:dsmlib_common.cpp:dsmlibp_register_factories:345 <4> Registerd Framework OBV factories with orb 0x158cb000 08:31:51.168 [16473] <2> dsmlibp_register_factories: dsmlib:dsmlib_common.cpp:dsmlibp_register_factories:353 <4> Registerd DiskServiceMgr OBV factories with orb 0x158cb000 08:31:51.169 [16473] <2> dsm_get_diskvolumeinfo_by_mediaid: dsmlib:dsmlib_diskgroup.cpp:dsm_get_diskvolumeinfo_by_mediaid:1687 <2> diskvolume media ID = @aaaar 08:31:51.169 [16473] <2> dsm_get_diskvolumeinfo_by_mediaid: dsmlib:dsmlib_diskgroup.cpp:dsm_get_diskvolumeinfo_by_mediaid:1690 <2> Calling dsm->getDiskVolumeInfoByMediaId() 08:31:51.181 [16473] <2> dsml_extract_diskvolumeinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskvolumeinfo:483 <2> diskvolume.name = ddboost-stu 08:31:51.181 [16473] <2> dsml_extract_diskvolumeinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskvolumeinfo:491 <2> diskvolume.id = ddboost-stu 08:31:51.181 [16473] <2> dsml_extract_diskvolumeinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskvolumeinfo:497 <2> diskvolumeinfo.total_capacity = 560951025205248 08:31:51.181 [16473] <2> dsml_extract_diskvolumeinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskvolumeinfo:501 <2> diskvolumeinfo.free_space = 317451907104768 08:31:51.181 [16473] <2> dsml_extract_diskvolumeinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskvolumeinfo:593 <2> property.name=DSM::master, property.value=mstr-nbkp-srv 08:31:51.181 [16473] <2> dsml_extract_diskvolumeinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskvolumeinfo:593 <2> property.name=DSM::flags, property.value=14 08:31:51.181 [16473] <2> dsml_extract_diskvolumeinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskvolumeinfo:568 <2> diskvolumeinfo.nbu_state = conv_fn(1) 08:31:51.181 [16473] <2> dsml_extract_diskvolumeinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskvolumeinfo:593 <2> property.name=DSM::fileSystemType, property.value=0 08:31:51.181 [16473] <2> dsml_extract_diskvolumeinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskvolumeinfo:593 <2> property.name=DSM::fileSystemOrdinal, property.value=0 08:31:51.181 [16473] <2> dsml_extract_diskvolumeinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskvolumeinfo:583 <2> diskvolumeinfo.max_write_mounts = 1 08:31:51.181 [16473] <2> dsml_extract_diskvolumeinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskvolumeinfo:588 <2> diskvolumeinfo.num_write_mounts = 1 08:31:51.182 [16473] <2> dsml_extract_diskvolumeinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskvolumeinfo:593 <2> property.name=DSM::maxReferenceWriters, property.value=2147483647 08:31:51.182 [16473] <2> dsml_extract_diskvolumeinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskvolumeinfo:573 <2> diskvolumeinfo.max_read_mounts = 0 08:31:51.182 [16473] <2> dsml_extract_diskvolumeinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskvolumeinfo:578 <2> diskvolumeinfo.num_read_mounts = 0 08:31:51.182 [16473] <2> dsml_extract_diskvolumeinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskvolumeinfo:593 <2> property.name=DSM::maxReferenceReaders, property.value=0 08:31:51.182 [16473] <2> dsml_extract_diskvolumeinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskvolumeinfo:593 <2> property.name=DSM::freeSpace, property.value=317451907104768 08:31:51.182 [16473] <2> dsml_extract_diskvolumeinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskvolumeinfo:593 <2> property.name=DSM::freePhysicalSpace, property.value=317451907104768 08:31:51.182 [16473] <2> dsml_extract_diskvolumeinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskvolumeinfo:530 <2> diskvolumeinfo.potential_free_space = 0 08:31:51.182 [16473] <2> dsml_extract_diskvolumeinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskvolumeinfo:535 <2> diskvolumeinfo.committed_space = 162954305936384 08:31:51.182 [16473] <2> dsml_extract_diskvolumeinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskvolumeinfo:540 <2> diskvolumeinfo.precommitted_space = 162947136560128 08:31:51.182 [16473] <2> dsml_extract_diskvolumeinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskvolumeinfo:550 <2> diskvolumeinfo.system_tag = Unknown 08:31:51.182 [16473] <2> dsml_extract_diskvolumeinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskvolumeinfo:545 <2> diskvolumeinfo.disk_media_id = @aaaar 08:31:51.182 [16473] <2> dsml_extract_diskvolumeinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskvolumeinfo:593 <2> property.name=DSM::activeReadStreams, property.value=0 08:31:51.182 [16473] <2> dsml_extract_diskvolumeinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskvolumeinfo:593 <2> property.name=DSM::activeWriteStreams, property.value=17 08:31:51.182 [16473] <2> dsml_extract_diskvolumeinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskvolumeinfo:593 <2> property.name=DSM::createdTime, property.value=1418752731 08:31:51.182 [16473] <2> dsml_extract_diskvolumeinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskvolumeinfo:593 <2> property.name=DSM::lastModifiedTime, property.value=1560916907 08:31:51.182 [16473] <2> dsml_extract_diskvolumeinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskvolumeinfo:593 <2> property.name=DSM::totalPhysicalCapacity, property.value=560951025205248 08:31:51.182 [16473] <2> dsml_extract_diskvolumeinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskvolumeinfo:593 <2> property.name=DSM::totalCapacity, property.value=560951025205248 08:31:51.182 [16473] <2> dsml_extract_diskvolumeinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskvolumeinfo:593 <2> property.name=DSMi::ReplicationSourceAndTargetSpecCount, property.value=0 08:31:51.182 [16473] <2> dsml_extract_diskvolumeinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskvolumeinfo:593 <2> property.name=DSM::majorEventSyncToken, property.value=1560916908 08:31:51.182 [16473] <2> dsml_extract_diskvolumeinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskvolumeinfo:593 <2> property.name=DSM::minorEventSyncToken, property.value=4504179 08:31:51.182 [16473] <2> dsml_extract_diskvolumeinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskvolumeinfo:593 <2> property.name=DSM::diskGroupName, property.value=DD990-DiskPool 08:31:51.182 [16473] <2> dsm_get_diskvolumeinfo_by_mediaid: dsmlib:dsmlib_diskgroup.cpp:dsm_get_diskvolumeinfo_by_mediaid:1713 <2> diskvolumeinfo.diskgroup.name = DD990-DiskPool 08:31:51.182 [16473] <2> dsm_get_diskvolumeinfo_by_mediaid: dsmlib:dsmlib_diskgroup.cpp:dsm_get_diskvolumeinfo_by_mediaid:1725 <2> diskvolumeinfo.diskgroup.master_server = mstr-nbkp-srv 08:31:51.182 [16473] <2> dsm_get_diskvolumeinfo_by_mediaid: dsmlib:dsmlib_diskgroup.cpp:dsm_get_diskvolumeinfo_by_mediaid:1736 <2> diskvolumeinfo.diskgroup.server_type = DataDomain 08:31:51.183 [16473] <2> dsml_populate_diskgroup: dsmlib:dsmlib_popext.cpp:dsml_populate_diskgroup:999 <2> diskgroup.name = DD990-DiskPool 08:31:51.183 [16473] <2> dsml_populate_diskgroup: dsmlib:dsmlib_popext.cpp:dsml_populate_diskgroup:1007 <2> diskgroup.master_server = mstr-nbkp-srv 08:31:51.183 [16473] <2> dsml_populate_diskgroup: dsmlib:dsmlib_popext.cpp:dsml_populate_diskgroup:1010 <2> diskgroup.server_type = DataDomain 08:31:51.183 [16473] <2> dsml_populate_diskgroup: dsmlib:dsmlib_popext.cpp:dsml_populate_diskgroup:1012 <2> diskgroup.access_media_server = 08:31:51.183 [16473] <2> dsm_get_diskgroupinfo: dsmlib:dsmlib_diskgroup.cpp:dsm_get_diskgroupinfo:405 <2> Calling dsm->getDiskGroupInfo() 08:31:51.226 [16473] <2> dsm_get_diskgroupinfo: dsmlib:dsmlib_diskgroup.cpp:dsm_get_diskgroupinfo:429 <2> Storing new DiskGroupInfo object reference 08:31:51.226 [16473] <2> dsml_extract_diskgroupinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskgroupinfo:196 <2> diskgroup.name = DD990-DiskPool 08:31:51.226 [16473] <2> dsml_extract_diskgroupinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskgroupinfo:205 <2> diskgroup.id = DD990-DiskPool 08:31:51.226 [16473] <2> dsml_extract_diskgroupinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskgroupinfo:214 <2> diskgroup.master_server = mstr-nbkp-srv 08:31:51.226 [16473] <2> dsml_extract_diskgroupinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskgroupinfo:224 <2> diskgroup.server_type = DataDomain 08:31:51.226 [16473] <2> dsml_extract_diskgroupinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskgroupinfo:232 <2> diskgroupinfo.total_capacity = 560951025205248 08:31:51.226 [16473] <2> dsml_extract_diskgroupinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskgroupinfo:236 <2> diskgroupinfo.used_space = 0 08:31:51.226 [16473] <2> dsml_extract_diskgroupinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskgroupinfo:317 <2> property.name=DSM::master, property.value=mstr-nbkp-srv 08:31:51.226 [16473] <2> dsml_extract_diskgroupinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskgroupinfo:270 <2> diskgroupinfo.nbu_state = conv_fn(2) 08:31:51.226 [16473] <2> dsml_extract_diskgroupinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskgroupinfo:317 <2> property.name=DSM::flags, property.value=1137911 08:31:51.226 [16473] <2> dsml_extract_diskgroupinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskgroupinfo:317 <2> property.name=DSM::flagsExt, property.value=0 08:31:51.226 [16473] <2> dsml_extract_diskgroupinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskgroupinfo:317 <2> property.name=DSM::fileSystemCardinal, property.value=0 08:31:51.226 [16473] <2> dsml_extract_diskgroupinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskgroupinfo:317 <2> property.name=DSM::usedSpace, property.value=0 08:31:51.226 [16473] <2> dsml_extract_diskgroupinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskgroupinfo:317 <2> property.name=DSM::usedPhysicalSpace, property.value=0 08:31:51.226 [16473] <2> dsml_extract_diskgroupinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskgroupinfo:297 <2> diskgroupinfo.high_watermark = 98 08:31:51.226 [16473] <2> dsml_extract_diskgroupinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskgroupinfo:302 <2> diskgroupinfo.low_watermark = 80 08:31:51.226 [16473] <2> dsml_extract_diskgroupinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskgroupinfo:307 <2> diskgroupinfo.system_tag = Created by NetBackup 08:31:51.226 [16473] <2> dsml_extract_diskgroupinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskgroupinfo:317 <2> property.name=DSM::maxIOStreams, property.value=-1 08:31:51.226 [16473] <2> dsml_extract_diskgroupinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskgroupinfo:317 <2> property.name=DSM::servertype, property.value=DataDomain 08:31:51.226 [16473] <2> dsml_extract_diskgroupinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskgroupinfo:317 <2> property.name=DSM::createdTime, property.value=1418752731 08:31:51.226 [16473] <2> dsml_extract_diskgroupinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskgroupinfo:317 <2> property.name=DSM::lastModifiedTime, property.value=0 08:31:51.226 [16473] <2> dsml_extract_diskgroupinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskgroupinfo:317 <2> property.name=DSM::totalPhysicalCapacity, property.value=560951025205248 08:31:51.226 [16473] <2> dsml_extract_diskgroupinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskgroupinfo:317 <2> property.name=DSM::totalCapacity, property.value=560951025205248 08:31:51.226 [16473] <2> dsml_extract_diskgroupinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskgroupinfo:281 <2> diskgroupinfo.availability = !Yes 08:31:51.227 [16473] <2> dsml_extract_diskgroupinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskgroupinfo:317 <2> property.name=DSM::majorEventSyncToken, property.value=1560916908 08:31:51.227 [16473] <2> dsml_extract_diskgroupinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskgroupinfo:317 <2> property.name=DSM::minorEventSyncToken, property.value=4504179 08:31:51.227 [16473] <2> dsml_extract_diskgroupinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskgroupinfo:325 <2> diskgroupinfo.num_diskvolumes = 1 08:31:51.227 [16473] <2> dsml_extract_diskgroupinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskgroupinfo:327 <2> diskgroupinfo.num_disks = 0 08:31:51.227 [16473] <2> dsml_extract_diskgroupinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_diskgroupinfo:329 <2> diskgroupinfo.num_stservers = 1 08:31:51.227 [16473] <2> dsm_get_diskgroup_stservers: dsmlib:dsmlib_diskgroup.cpp:dsm_get_diskgroup_stservers:521 <2> DiskGroupInfo - #stservers = 1 08:31:51.227 [16473] <2> dsml_extract_storageserver: dsmlib:dsmlib_popext.cpp:dsml_extract_storageserver:757 <2> stserver.name = DD990-1 08:31:51.227 [16473] <2> dsml_extract_storageserver: dsmlib:dsmlib_popext.cpp:dsml_extract_storageserver:763 <2> stserver.id = 08:31:51.227 [16473] <2> dsml_extract_storageserver: dsmlib:dsmlib_popext.cpp:dsml_extract_storageserver:769 <2> stserver.server_type = DataDomain 08:31:51.227 [16473] <2> dsml_extract_storageserver: dsmlib:dsmlib_popext.cpp:dsml_extract_storageserver:772 <2> stserver.storage_type = 9 08:31:51.227 [16473] <2> dsml_extract_storageserver: dsmlib:dsmlib_popext.cpp:dsml_extract_storageserver:795 <2> property.name=DSM::activeJobs, property.value=0 08:31:51.227 [16473] <2> dsml_extract_storageserver: dsmlib:dsmlib_popext.cpp:dsml_extract_storageserver:795 <2> property.name=DSM::flags, property.value=22858153 08:31:51.227 [16473] <2> dsml_extract_storageserver: dsmlib:dsmlib_popext.cpp:dsml_extract_storageserver:795 <2> property.name=DSM::flagsExt, property.value=0 08:31:51.227 [16473] <2> dsml_extract_storageserver: dsmlib:dsmlib_popext.cpp:dsml_extract_storageserver:795 <2> property.name=DSM::state, property.value=1 08:31:51.227 [16473] <2> dsml_extract_storageserver: dsmlib:dsmlib_popext.cpp:dsml_extract_storageserver:795 <2> property.name=DSMi::machineKey, property.value=1000041 08:31:51.228 [16473] <2> dsml_populate_stslist: dsmlib:dsmlib_popext.cpp:dsml_populate_stslist:955 <2> num_stservers = 1 08:31:51.228 [16473] <2> dsml_populate_stslist: dsmlib:dsmlib_popext.cpp:dsml_populate_stslist:961 <2> stservers[0].name = DD990-1 08:31:51.228 [16473] <2> dsml_populate_stslist: dsmlib:dsmlib_popext.cpp:dsml_populate_stslist:967 <2> stservers[0].server_type = DataDomain 08:31:51.228 [16473] <2> dsml_populate_stslist: dsmlib:dsmlib_popext.cpp:dsml_populate_stslist:969 <2> stservers[0].access_media_server = 08:31:51.228 [16473] <2> dsm_get_storageserverinfo: dsmlib:dsmlib_enclosure.cpp:dsm_get_storageserverinfo:533 <2> Calling dsm->getStorageServerInfo() 08:31:51.256 [16473] <2> dsm_get_storageserverinfo: dsmlib:dsmlib_enclosure.cpp:dsm_get_storageserverinfo:562 <2> Storing new StorageServerInfo object reference 08:31:51.256 [16473] <2> dsml_extract_storageserver: dsmlib:dsmlib_popext.cpp:dsml_extract_storageserver:757 <2> stserver.name = DD990-1 08:31:51.256 [16473] <2> dsml_extract_storageserver: dsmlib:dsmlib_popext.cpp:dsml_extract_storageserver:763 <2> stserver.id = 08:31:51.256 [16473] <2> dsml_extract_storageserver: dsmlib:dsmlib_popext.cpp:dsml_extract_storageserver:769 <2> stserver.server_type = DataDomain 08:31:51.256 [16473] <2> dsml_extract_storageserver: dsmlib:dsmlib_popext.cpp:dsml_extract_storageserver:772 <2> stserver.storage_type = 9 08:31:51.256 [16473] <2> dsml_extract_storageserver: dsmlib:dsmlib_popext.cpp:dsml_extract_storageserver:795 <2> property.name=DSM::activeJobs, property.value=0 08:31:51.256 [16473] <2> dsml_extract_storageserver: dsmlib:dsmlib_popext.cpp:dsml_extract_storageserver:795 <2> property.name=DSM::flags, property.value=22858153 08:31:51.256 [16473] <2> dsml_extract_storageserver: dsmlib:dsmlib_popext.cpp:dsml_extract_storageserver:795 <2> property.name=DSM::flagsExt, property.value=0 08:31:51.256 [16473] <2> dsml_extract_storageserver: dsmlib:dsmlib_popext.cpp:dsml_extract_storageserver:795 <2> property.name=DSM::state, property.value=1 08:31:51.256 [16473] <2> dsml_extract_storageserver: dsmlib:dsmlib_popext.cpp:dsml_extract_storageserver:795 <2> property.name=DSMi::machineKey, property.value=1000041 08:31:51.256 [16473] <2> dsml_extract_storageserver: dsmlib:dsmlib_popext.cpp:dsml_extract_storageserver:795 <2> property.name=DSM::StuSubType, property.value=6 08:31:51.256 [16473] <2> dsml_extract_storageserver: dsmlib:dsmlib_popext.cpp:dsml_extract_storageserver:795 <2> property.name=DSM::servertype, property.value=DataDomain 08:31:51.256 [16473] <2> dsml_extract_storageserverinfo: dsmlib:dsmlib_popext.cpp:dsml_extract_storageserverinfo:835 <2> stserverinfo.property_flags = 22858153 08:31:51.256 [16473] <2> check_optimization: NDMP_FSE_HOST flag is not set on storage server DD990-1 (flags = 22858153) 08:31:51.256 [16473] <2> check_optimization: Optimized duplication will not be used - destination is not disk 08:31:51.256 [16473] <2> calculate_request_timeout: Request timeout to jmcomm_RequestInitialResources is 0 seconds. 08:31:51.257 [16473] <2> Orb::init: Enabling ORBNativeCharCodeSet UTF-8(Orb.cpp:801) 08:31:51.257 [16473] <2> Orb::init: Created anon service name: NB_16473_593972456(Orb.cpp:883) 08:31:51.257 [16473] <2> Orb::init: endpointvalue is : pbxiop://1556:NB_16473_593972456(Orb.cpp:893) 08:31:51.257 [16473] <2> Orb::init: initializing ORB Default_DAEMON_Orb with: Unknown -ORBSvcConfDirective "-ORBDottedDecimalAddresses 0" -ORBSvcConfDirective "static Resource_Factory '-ORBNativeCharCodeSet UTF-8'" -ORBSvcConfDirective "static PBXIOP_Factory '-enable_keepalive'" -ORBSvcConfDirective "static EndpointSelectorFactory ''" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory PBXIOP_Factory'" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory IIOP_Factory'" -ORBDefaultInitRef '' -ORBSvcConfDirective "static PBXIOP_Evaluator_Factory '-orb Default_DAEMON_Orb'" -ORBSvcConfDirective "static Resource_Factory '-ORBConnectionCacheMax 1024 '" -ORBEndpoint pbxiop://1556:NB_16473_593972456 -ORBSvcConf /dev/null -ORBSvcConfDirective "static Server_Strategy_Factory '-ORBMaxRecvGIOPPayloadSize 268435456'"(Orb.cpp:1015) 08:31:51.258 [16473] <2> Orb::init: caching EndpointSelectorFactory(Orb.cpp:1029) 08:31:52.677 [16473] <2> RequestInitialResources: returning 08:31:52.678 [16473] <2> jmcomm_PrintStuResults: requestStatus is 0 08:31:52.678 [16473] <2> jmcomm_PrintStuResults: allocationId : {F81E9E86-9246-11E9-8B4F-5B4650151362} 08:31:52.678 [16473] <2> jmcomm_PrintStuResults: results.mdr_AllocationKey : 3243115 08:31:52.678 [16473] <2> jmcomm_PrintStuResults: results.mdr_TpReqFileName : 08:31:52.678 [16473] <2> jmcomm_PrintStuResults: results.mdar_MediaServer : MEDIA_Y 08:31:52.678 [16473] <2> jmcomm_PrintStuResults: results.mdar_MediaServerVersion : 750000 08:31:52.678 [16473] <2> jmcomm_PrintStuResults: results.mdar_MediaServerClusterName : 08:31:52.678 [16473] <2> jmcomm_PrintStuResults: results.mdar_MediaKey : 4008795 08:31:52.678 [16473] <2> jmcomm_PrintStuResults: results.mdar_MediaId : T10086 08:31:52.678 [16473] <2> jmcomm_PrintStuResults: results.mdar_DriveKey : 2000597 08:31:52.678 [16473] <2> jmcomm_PrintStuResults: results.mdar_DriveName : HP.ULTRIUM6-SCSI.043 08:31:52.678 [16473] <2> jmcomm_PrintStuResults: results.mdar_PrimaryPath : /dev/nst14 08:31:52.678 [16473] <2> jmcomm_PrintStuResults: results.mdar_PoolName : DISK_POOL 08:31:52.678 [16473] <2> jmcomm_PrintStuResults: results.mdar_NdmpControlHost : 08:31:52.678 [16473] <2> jmcomm_PrintStuResults: results.mdar_NdmpUserName : 08:31:52.678 [16473] <2> jmcomm_PrintStuResults: results.mdar_NdmpClientUserName : 08:31:52.678 [16473] <2> jmcomm_PrintStuResults: results.mdar_RetentionLevel : 24 08:31:52.678 [16473] <2> jmcomm_PrintStuResults: results.mdar_PolicyType : 2 08:31:52.678 [16473] <2> jmcomm_PrintStuResults: results.mdar_JobType : 9 08:31:52.678 [16473] <2> jmcomm_PrintStuResults: results.mdar_MasterServer : mstr-nbkp-srv 08:31:52.678 [16473] <2> jmcomm_PrintStuResults: results.sturec_STUName : MEDIA_Y-hcart3-Qi6K-tld-2 08:31:52.678 [16473] <2> jmcomm_PrintStuResults: results.sturec_MasterServer : mstr-nbkp-srv 08:31:52.678 [16473] <2> jmcomm_PrintStuResults: results.sturec_STUType : 2 08:31:52.678 [16473] <2> jmcomm_PrintStuResults: results.sturec_MediaServer : MEDIA_Y 08:31:52.678 [16473] <2> jmcomm_PrintStuResults: results.sturec_RobotType : 8 08:31:52.678 [16473] <2> jmcomm_PrintStuResults: results.sturec_RobotNumber : 2 08:31:52.678 [16473] <2> jmcomm_PrintStuResults: results.sturec_Density : 20 08:31:52.678 [16473] <2> jmcomm_PrintStuResults: results.sturec_DriveGroupName : 08:31:52.678 [16473] <2> jmcomm_PrintStuResults: results.sturec_ConcurrentJobs : 20 08:31:52.678 [16473] <2> jmcomm_PrintStuResults: results.sturec_ActiveJobs : 0 08:31:52.678 [16473] <2> jmcomm_PrintStuResults: results.sturec_AbsolutePath : 08:31:52.678 [16473] <2> jmcomm_PrintStuResults: results.sturec_InitialMultiplexing : 0 08:31:52.678 [16473] <2> jmcomm_PrintStuResults: results.sturec_OnDemandOnly : 0 08:31:52.678 [16473] <2> jmcomm_PrintStuResults: results.sturec_MaxMultiplexing : 4 08:31:52.678 [16473] <2> jmcomm_PrintStuResults: results.sturec_MaxFragmentSize : 1048576 08:31:52.678 [16473] <2> jmcomm_PrintStuResults: results.sturec_NdmpAttachHost : 08:31:52.678 [16473] <2> jmcomm_PrintStuResults: results.sturec_StuFlags : 0x0 08:31:52.678 [16473] <2> jmcomm_PrintStuResults: results.sturec_StuSubtype : 1 08:31:52.678 [16473] <2> jmcomm_PrintStuResults: results.sturec_Throttle : 0 08:31:52.678 [16473] <2> jmcomm_PrintStuResults: results.sturec_HighWaterMark : 0 08:31:52.678 [16473] <2> jmcomm_PrintStuResults: results.sturec_LowWaterMark : 0 08:31:52.678 [16473] <2> jmcomm_PrintStuResults: results.sturec_OkOnRoot : 1 08:31:52.678 [16473] <2> jmcomm_PrintStuResults: Bptm message : [MEDIADB 1 3243115 T10086 4008795 ------ 20 1560916911 0 0 0 0 0 0 24 11 0 0 0 0 0 0] 08:31:52.678 [16473] <2> jmcomm_PrintStuResults: Bptm message : [VOLUME 1 T10086 4008795 T10086L6 DISK_POOL QUANTUM 0170420231 24 8 2 1147 0 {00000000-0000-0000-0000-000000000000} 0] 08:31:52.678 [16473] <2> jmcomm_PrintStuResults: Bptm message : [DRIVE 3 HP.ULTRIUM6-SCSI.043 2000597 F00286F085 /dev/nst14 -1 -1 -1 -1 -1 -1 -1 -1 *NULL* *NULL* *NULL* *NULL* 1 0 0 1 0 0] 08:31:52.678 [16473] <2> jmcomm_PrintStuResults: Bptm message : [STORAGE 1 MEDIA_Y-hcart3-Qi6K-tld-2 20 1048576 2 1 0 0 MEDIA_Y MEDIA_Y *NULL*] 08:31:52.678 [16473] <2> jmcomm_PrintStuResults: Bptm message : [DISKGROUP 0 6 *NULL* 6 *NULL* 6 *NULL*] 08:31:52.678 [16473] <2> jmcomm_PrintStuResults: Bptm message : [DISKVOLUME 0 6 *NULL* 6 *NULL* 0] 08:31:52.678 [16473] <2> jmcomm_PrintStuResults: Bptm message : [DISKMOUNTPOINT 0 6 *NULL*] 08:31:52.678 [16473] <2> jmcomm_PrintStuResults: useFatPipeTransport is 0 08:31:52.678 [16473] <2> jmcomm_PrintStuResults: diskPath is 08:31:52.678 [16473] <2> jmcomm_PrintStuResults: failureCode is 0 08:31:52.678 [16473] <2> jmcomm_PrintMediaResults: requestStatus is 0 08:31:52.678 [16473] <2> jmcomm_PrintMediaResults: allocationId : {F821CBA6-9246-11E9-B8F5-CC74CD78E6FF} 08:31:52.678 [16473] <2> jmcomm_PrintMediaResults: results.mdr_AllocationKey : 3243114 08:31:52.678 [16473] <2> jmcomm_PrintMediaResults: results.mdr_TpReqFileName : 08:31:52.678 [16473] <2> jmcomm_PrintMediaResults: results.mdar_MediaServer: mstr-nbkp-srv 08:31:52.678 [16473] <2> jmcomm_PrintMediaResults: results.mdar_MediaServerVersion : 772000 08:31:52.678 [16473] <2> jmcomm_PrintMediaResults: results.mdar_MediaServerClusterName: 08:31:52.678 [16473] <2> jmcomm_PrintMediaResults: results.mdar_MediaKey : 17 08:31:52.678 [16473] <2> jmcomm_PrintMediaResults: results.mdar_MediaId : @aaaar 08:31:52.678 [16473] <2> jmcomm_PrintMediaResults: results.mdar_DriveKey : 0 08:31:52.678 [16473] <2> jmcomm_PrintMediaResults: results.mdar_DriveName : 08:31:52.678 [16473] <2> jmcomm_PrintMediaResults: results.mdar_PrimaryPath : 08:31:52.679 [16473] <2> jmcomm_PrintMediaResults: results.mdar_PoolName : 08:31:52.679 [16473] <2> jmcomm_PrintMediaResults: results.mdar_NdmpControlHost : 08:31:52.679 [16473] <2> jmcomm_PrintMediaResults: results.mdar_NdmpUserName : 08:31:52.679 [16473] <2> jmcomm_PrintMediaResults: results.mdar_NdmpClientUserName : 08:31:52.679 [16473] <2> jmcomm_PrintMediaResults: results.mdar_RetentionLevel : 0 08:31:52.679 [16473] <2> jmcomm_PrintMediaResults: results.mdar_PolicyType : 2 08:31:52.679 [16473] <2> jmcomm_PrintMediaResults: results.mdar_JobType : 10 08:31:52.679 [16473] <2> jmcomm_PrintMediaResults: results.mdar_MasterServer : mstr-nbkp-srv 08:31:52.679 [16473] <2> jmcomm_PrintMediaResults: Bptm message : [MEDIADB 1 3243114 @aaaar 17 *NULL* 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] 08:31:52.679 [16473] <2> jmcomm_PrintMediaResults: Bptm message : [VOLUME 1 @aaaar 17 *NULL* *NULL* *NULL* *NULL* 0 0 0 0 0 {00000000-0000-0000-0000-000000000000} 0] 08:31:52.679 [16473] <2> jmcomm_PrintMediaResults: Bptm message : [DRIVE 4 *NULL* 0 *NULL* *NULL* 0 0 0 0 0 0 0 0 *NULL* *NULL* *NULL* *NULL* 0 0 0 0 0 0 ] 08:31:52.679 [16473] <2> jmcomm_PrintMediaResults: Bptm message : [STORAGE 1 *NULL* 0 0 0 0 0 0 MASTER_SERVERMASTER_SERVER*NULL*] 08:31:52.679 [16473] <2> jmcomm_PrintMediaResults: Bptm message : [DISKGROUP 0 10 DataDomain 7 DD990-1 14 DD990-DiskPool] 08:31:52.679 [16473] <2> jmcomm_PrintMediaResults: Bptm message : [DISKVOLUME 0 6 @aaaar 11 ddboost-stu 0] 08:31:52.679 [16473] <2> jmcomm_PrintMediaResults: Bptm message : [DISKMOUNTPOINT 0 11 ddboost-stu] 08:31:52.679 [16473] <2> jmcomm_PrintMediaReserveResults: requestStatus is 0 08:31:52.679 [16473] <2> jmcomm_PrintMediaReserveResults: mediaReserve->results.mreq_mediaServer : 08:31:52.679 [16473] <2> jmcomm_PrintMediaReserveResults: mediaReserve->results.mreq_mediaId : @aaaar 08:31:52.679 [16473] <2> jmcomm_PrintMediaReserveResults: mediaReserve->results.mreq_mediaKey : 17 08:31:52.679 [16473] <2> jmcomm_PrintMediaReserveResults: mediaReserve->results.mreq_userReservationId : 08:31:52.679 [16473] <2> jmcomm_PrintMediaReserveResults: mediaReserve->results.mreq_assignedTime : 0 08:31:52.679 [16473] <2> jmcomm_PrintMediaReserveResults: mediaReserve->results.mreq_client : 08:31:52.679 [16473] <2> jmcomm_PrintMediaReserveResults: mediaReserve->results.mreq_usageType : 0 08:31:52.679 [16473] <2> jmcomm_PrintMediaReserveResults: mediaReserve->results.mreq_mustBeNdmp : false 08:31:52.679 [16473] <2> jmcomm_PrintMediaReserveResults: mediaReserve->results.mreq_driveName : 08:31:52.679 [16473] <2> jmcomm_PrintMediaReserveResults: mediaReserve->results.mreq_drivePath : 08:31:52.679 [16473] <2> jmcomm_PrintMediaReserveResults: mediaReserve->results.mreq_mediaPool : 08:31:52.679 [16473] <2> jmcomm_PrintMediaReserveResults: mediaReserve->results.mreq_robotNumber : -1 08:31:52.679 [16473] <2> jmcomm_PrintMediaReserveResults: mediaReserve->results.mreq_slotNumber : -1 08:31:52.679 [16473] <2> jmcomm_PrintMediaReserveResults: mediaReserve->results.mreq_density : -1 08:31:52.679 [16473] <2> jmcomm_PrintMediaReserveResults: mediaReserve->results.mreq_ndmpControlHost : 08:31:52.679 [16473] <2> jmcomm_PrintMediaReserveResults: mediaReserve->results.mreq_failIfNoMedia : false 08:31:52.679 [16473] <2> jmcomm_PrintMediaReserveResults: mediaReserve->results.mreq_externalFile : 08:31:52.679 [16473] <2> bpduplicate: Destination storage unit MEDIA_Y-hcart3-Qi6K-tld-2 on host MEDIA_Y 08:31:52.679 [16473] <2> db_fork_lock_dbm: Sending Q_IMAGE_FORK_LOCK_DBM 08:31:52.679 [16473] <8> vnet_get_user_credential_path: [vnet_vxss.c:1474] status 35 0x23 08:31:52.680 [16473] <8> vnet_check_user_certificate: [vnet_vxss_helper.c:3643] vnet_get_user_credential_path failed 35 0x23 08:31:52.680 [16473] <2> ConnectionCache::connectAndCache: Acquiring new connection for host mstr-nbkp-srv, query type 110 08:31:52.680 [16473] <2> logconnections: BPDBM CONNECT FROM 127.0.0.1.52368 TO 127.0.0.1.13721 fd = 18 08:31:52.683 [16473] <2> bpduplicate: Duplicating policy POLICY_NAME schedule MEDIA_X-backup03-dd backup id MEDIA_X_1443523049 copy 1 created on 09/29/2015 14:07:29 on source path @aaaar 08:31:52.683 [16473] <4> bpduplicate: EMM interface already initialized (using cached master name mstr-nbkp-srv). 08:31:52.708 [16473] <2> bpduplicate: version of write_host MEDIA_Y is 750000 08:31:52.708 [16473] <2> orig_dup: locking MEDIA_X_1443523049 on lock_fd -1 copy 2 called at line <3260> 08:31:52.708 [16473] <2> db_lock_copy_byte: Sending LOCK_COPY 08:31:52.762 [16473] <2> bpduplicate: Obtained copy lock for backupid MEDIA_X_1443523049, copy 2, fd: 6 08:31:52.762 [16473] <2> orig_dup: locking source MEDIA_X_1443523049 on lock_fd 6 copy 1 called at line <3266> 08:31:52.762 [16473] <2> db_lock_copy_byte: Sending LOCK_COPY_EXISTING 08:31:52.771 [16473] <2> bpduplicate: Obtained copy lock for backupid MEDIA_X_1443523049, copy 1, fd: 6 08:31:52.771 [16473] <2> build_write_cmd: /usr/openv/netbackup/bin/bptm bptm -dup -cmd -nosig -L /usr/openv/netbackup/logs/user_ops/root/logs/jbpDuplicate-20190619083149.log -ru root -rclnt MASTER_SERVER-jobid 4025533 -mediasvr MEDIA_Y -c MEDIA_X -b MEDIA_X_1443523049 -cl POLICY_NAME -sl MEDIA_X-backup03-dd -bt 1443523049 -st 0 -rl 24 -date 2147483647 -ct 0 -den 20 -rt 8 -rn 2 -p DISK_POOL -twin_fail_on_error 0 -cn 2 -maxfrag 1048576 -stunit MEDIA_Y-hcart3-Qi6K-tld-2 -cj 20 -sg *ANY* -use_vnetd -df 1 08:31:52.804 [16473] <2> vnet_pbxConnect: pbxConnectEx Succeeded 08:31:52.804 [16473] <2> logconnections: BPCD CONNECT FROM MASTER_IP.33947 TO MEDIA_Y_IP.1556 fd = 19 08:31:52.826 [16473] <2> vnet_pbxConnect: pbxConnectEx Succeeded 08:31:52.828 [16473] <8> do_pbx_service: [vnet_connect.c:2227] via PBX VNETD CONNECT FROM MASTER_IP.49124 TO MEDIA_Y_IP.1556 fd = 20 08:31:52.828 [16473] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:440] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa 08:31:52.870 [16473] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:457] ipc_string /tmp/vnet-69313560916912874076000000016-n0nncf 08:31:53.064 [16473] <2> dupreadline: write pid = 69314 08:31:54.268 [16473] <2> build_read_cmd: /usr/openv/netbackup/bin/bpdm bpdm -copy -cmd -nosig -everything -cn 1 -c MEDIA_X -b MEDIA_X_1443523049 -ipc /tmp/vnet-69314560916914272741000000010-Szin2V NULL MEDIA_Y -L /usr/openv/netbackup/logs/user_ops/root/logs/jbpDuplicate-20190619083149.log -ru root -rclnt MASTER_SERVER-jobid 4025533 -p @aaaar -mediasvr MASTER_SERVER(host mstr-nbkp-srv) 08:31:54.268 [16473] <2> logconnections: BPCD CONNECT FROM 127.0.0.1.39476 TO 127.0.0.1.13782 fd = 19 08:31:54.273 [16473] <2> bpcr_get_version_rqst: bpcd version: 07720000 08:31:54.421 [16473] <2> dupreadline: read pid = 16800 08:31:54.421 [16473] <2> wait_for_statuses: Received read status: 0, 0, 1, 0, 0, 0 08:31:55.964 [16473] <2> wait_for_statuses: Received read status: 0, 83, 0, 0, 0, 0 08:31:55.964 [16473] <2> wait_for_statuses: Terminating bptm write process 69314 on MEDIA_Y 08:31:55.965 [16473] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:31:55.965 [16473] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:31:55.965 [16473] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:31:55.965 [16473] <2> read_client: dname=OAA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:31:55.965 [16473] <2> read_client: dname=COA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:31:55.965 [16473] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:31:55.965 [16473] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:31:55.966 [16473] <2> vnet_pbxConnect: pbxConnectEx Succeeded 08:31:55.967 [16473] <2> logconnections: BPCD CONNECT FROM MASTER_IP.51987 TO MEDIA_Y_IP.1556 fd = 19 08:31:55.968 [16473] <2> vnet_pbxConnect: pbxConnectEx Succeeded 08:31:55.970 [16473] <8> do_pbx_service: [vnet_connect.c:2227] via PBX VNETD CONNECT FROM MASTER_IP.32850 TO MEDIA_Y_IP.1556 fd = 21 08:31:55.970 [16473] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:440] VN_REQUEST_CONNECT_FORWARD_SOCKET 10 0xa 08:31:56.010 [16473] <8> vnet_vnetd_connect_forward_socket_begin: [vnet_vnetd.c:457] ipc_string /tmp/vnet-69367560916916015180000000016-JFsZpn 08:31:56.091 [16473] <2> bpcr_send_signal: CLIENT_CMD_SOCK from bpcr = 19 08:31:56.091 [16473] <2> bpcr_send_signal: CLIENT_STAT_SOCK from bpcr = 21 08:31:56.092 [16473] <2> bpduplicate: bpduplicate sent EXIT STATUS acknowledgement to reader, status: 0, err: 0 08:31:58.250 [16473] <2> wait_for_statuses: Received write status: 0, 82, 0 08:31:58.251 [16473] <2> wait_for_statuses: bpduplicate sent EXIT STATUS acknowledgement to writer, status: 0, err: 0 08:31:58.251 [16473] <16> bpduplicate: host MASTER_SERVERbackup id MEDIA_X_1443523049 read failed, media open error (83). 08:31:58.251 [16473] <16> bpduplicate: host MEDIA_Y backupid MEDIA_X_1443523049 write failed, media manager killed by signal (82). 08:31:58.251 [16473] <8> vnet_get_user_credential_path: [vnet_vxss.c:1474] status 35 0x23 08:31:58.251 [16473] <8> vnet_check_user_certificate: [vnet_vxss_helper.c:3643] vnet_get_user_credential_path failed 35 0x23 08:31:58.251 [16473] <2> ConnectionCache::connectAndCache: Acquiring new connection for host mstr-nbkp-srv, query type 64 08:31:58.252 [16473] <2> logconnections: BPDBM CONNECT FROM 127.0.0.1.54479 TO 127.0.0.1.13721 fd = 19 08:31:58.255 [16473] <2> db_end: Need to collect reply 08:31:58.375 [16473] <2> bpduplicate: unlocking MEDIA_X_1443523049 on lock_fd 6 copy 2 called at line <3909> 08:31:58.375 [16473] <2> db_unlock_copy_byte: Sending UNLOCK_COPY 08:31:58.376 [16473] <2> bpduplicate: Unlocked copy lock for backupid MEDIA_X_1443523049, copy 2 08:31:58.376 [16473] <16> bpduplicate: Duplicate of backupid MEDIA_X_1443523049 failed, media manager killed by signal (82). 08:31:58.376 [16473] <2> bpduplicate: unlocking source MEDIA_X_1443523049 copy 1 called at line <3950> with lock_fd <6> 08:31:58.376 [16473] <2> db_unlock_copy_byte: Sending UNLOCK_COPY 08:31:58.377 [16473] <2> bpduplicate: Unlocked copy lock for backupid MEDIA_X_1443523049, copy 1 08:31:58.377 [16473] <2> db_terminate_lock_dbm: Sending TERMINATE_COPY 08:31:58.386 [16473] <2> db_terminate_lock_dbm: Received DONE 08:31:58.386 [16473] <2> db_end: Need to collect reply 08:31:58.388 [16473] <16> bpduplicate: Status = no images were successfully processed. 08:31:58.390 [16473] <2> jmcomm_ReleaseAllResourcesForJob: releaseAllResources returns [0] 08:31:58.390 [16473] <2> jmcomm_ReleaseAllResourcesForJob: returning 08:31:58.390 [16473] <2> cleanup: EXIT status = 191 08:31:58.391 [16473] <2> bpduplicate: closing progress log(s) 08:31:58.408 [16473] <2> job_monitoring_exex: ACK disconnect 08:31:58.408 [16473] <2> job_disconnect: Disconnected 08:32:20.802 [18809] <2> nbemmcmd: VERBOSE = 0 08:32:20.806 [18809] <8> vnet_get_user_credential_path: [vnet_vxss.c:1474] status 35 0x23 08:32:20.806 [18809] <8> vnet_check_user_certificate: [vnet_vxss_helper.c:3643] vnet_get_user_credential_path failed 35 0x23 08:32:20.806 [18809] <2> : NBEMMCMD -listsettings -brief -emmname MASTER_SERVER-emmport 1556 -machinename MEDIA_Y 08:32:20.844 [18809] <2> Orb::init: Enabling ORBNativeCharCodeSet UTF-8(Orb.cpp:801) 08:32:20.845 [18809] <2> Orb::init: initializing ORB EMMlib_Orb with: nbemmcmd -ORBSvcConfDirective "-ORBDottedDecimalAddresses 0" -ORBSvcConfDirective "static Resource_Factory '-ORBNativeCharCodeSet UTF-8'" -ORBSvcConfDirective "static PBXIOP_Factory '-enable_keepalive'" -ORBSvcConfDirective "static EndpointSelectorFactory ''" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory PBXIOP_Factory'" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory IIOP_Factory'" -ORBDefaultInitRef '' -ORBSvcConfDirective "static PBXIOP_Evaluator_Factory '-orb EMMlib_Orb'" -ORBSvcConfDirective "static Resource_Factory '-ORBConnectionCacheMax 1024 '" -ORBSvcConf /dev/null -ORBSvcConfDirective "static Server_Strategy_Factory '-ORBMaxRecvGIOPPayloadSize 268435456'"(Orb.cpp:1015) 08:32:20.846 [18809] <2> Orb::init: caching EndpointSelectorFactory(Orb.cpp:1029) 08:32:20.847 [18809] <2> Orb::setOrbConnectTimeout: timeout seconds: 60(Orb.cpp:1380) 08:32:20.847 [18809] <2> Orb::setOrbRequestTimeout: timeout seconds: 1800(Orb.cpp:1389) 08:32:31.628 [19665] <2> nbemmcmd: VERBOSE = 0 08:32:31.631 [19665] <8> vnet_get_user_credential_path: [vnet_vxss.c:1474] status 35 0x23 08:32:31.631 [19665] <8> vnet_check_user_certificate: [vnet_vxss_helper.c:3643] vnet_get_user_credential_path failed 35 0x23 08:32:31.632 [19665] <2> : NBEMMCMD -listsettings -brief -emmname MASTER_SERVER-emmport 1556 -machinename MEDIA_Y 08:32:31.670 [19665] <2> Orb::init: Enabling ORBNativeCharCodeSet UTF-8(Orb.cpp:801) 08:32:31.670 [19665] <2> Orb::init: initializing ORB EMMlib_Orb with: nbemmcmd -ORBSvcConfDirective "-ORBDottedDecimalAddresses 0" -ORBSvcConfDirective "static Resource_Factory '-ORBNativeCharCodeSet UTF-8'" -ORBSvcConfDirective "static PBXIOP_Factory '-enable_keepalive'" -ORBSvcConfDirective "static EndpointSelectorFactory ''" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory PBXIOP_Factory'" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory IIOP_Factory'" -ORBDefaultInitRef '' -ORBSvcConfDirective "static PBXIOP_Evaluator_Factory '-orb EMMlib_Orb'" -ORBSvcConfDirective "static Resource_Factory '-ORBConnectionCacheMax 1024 '" -ORBSvcConf /dev/null -ORBSvcConfDirective "static Server_Strategy_Factory '-ORBMaxRecvGIOPPayloadSize 268435456'"(Orb.cpp:1015) 08:32:31.672 [19665] <2> Orb::init: caching EndpointSelectorFactory(Orb.cpp:1029) 08:32:31.672 [19665] <2> Orb::setOrbConnectTimeout: timeout seconds: 60(Orb.cpp:1380) 08:32:31.672 [19665] <2> Orb::setOrbRequestTimeout: timeout seconds: 1800(Orb.cpp:1389) 08:32:40.021 [20405] <2> bpclient: VERBOSE = 0 08:32:40.021 [20405] <2> bpclient: INITIATING: version NetBackup 7.7.2 created: 2016011116 08:32:40.021 [20405] <2> logparams: -M MASTER_SERVER-All -java 08:32:40.021 [20405] <2> bpclient: listing all clients in test mode 08:32:40.021 [20405] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.021 [20405] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.021 [20405] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.021 [20405] <2> read_client: dname=OAA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.021 [20405] <2> read_client: dname=COA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.021 [20405] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.021 [20405] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.025 [20405] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.025 [20405] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.025 [20405] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.025 [20405] <2> read_client: dname=OAA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.025 [20405] <2> read_client: dname=COA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.025 [20405] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.025 [20405] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.026 [20405] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.026 [20405] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.026 [20405] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.026 [20405] <2> read_client: dname=OAA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.026 [20405] <2> read_client: dname=COA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.026 [20405] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.026 [20405] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.028 [20405] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.028 [20405] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.028 [20405] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.028 [20405] <2> read_client: dname=OAA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.028 [20405] <2> read_client: dname=COA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.028 [20405] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.028 [20405] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.028 [20405] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.028 [20405] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.028 [20405] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.028 [20405] <2> read_client: dname=OAA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.028 [20405] <2> read_client: dname=COA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.028 [20405] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.028 [20405] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.028 [20405] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.028 [20405] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.028 [20405] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.028 [20405] <2> read_client: dname=OAA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.028 [20405] <2> read_client: dname=COA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.028 [20405] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.028 [20405] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.032 [20405] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.032 [20405] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.032 [20405] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.032 [20405] <2> read_client: dname=OAA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.032 [20405] <2> read_client: dname=COA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.032 [20405] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.032 [20405] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.037 [20405] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.037 [20405] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.037 [20405] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.037 [20405] <2> read_client: dname=OAA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.037 [20405] <2> read_client: dname=COA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.037 [20405] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.037 [20405] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.049 [20405] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.049 [20405] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.049 [20405] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.049 [20405] <2> read_client: dname=OAA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.049 [20405] <2> read_client: dname=COA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.049 [20405] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.049 [20405] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.049 [20405] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.049 [20405] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.049 [20405] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.049 [20405] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.049 [20405] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.049 [20405] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.050 [20405] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.050 [20405] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.050 [20405] <2> read_client: dname=OAA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.050 [20405] <2> read_client: dname=COA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.050 [20405] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.050 [20405] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.050 [20405] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.050 [20405] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.050 [20405] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.050 [20405] <2> read_client: dname=OAA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.050 [20405] <2> read_client: dname=COA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.050 [20405] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.050 [20405] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.050 [20405] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.050 [20405] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.050 [20405] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.050 [20405] <2> read_client: dname=OAA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.050 [20405] <2> read_client: dname=COA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.050 [20405] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.050 [20405] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.055 [20405] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.055 [20405] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.055 [20405] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.055 [20405] <2> read_client: dname=OAA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.055 [20405] <2> read_client: dname=COA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.055 [20405] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.055 [20405] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.055 [20405] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.055 [20405] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.055 [20405] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.055 [20405] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.055 [20405] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.056 [20405] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.056 [20405] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.056 [20405] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.056 [20405] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.056 [20405] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.056 [20405] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.056 [20405] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.056 [20405] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.056 [20405] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.056 [20405] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.056 [20405] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.056 [20405] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.056 [20405] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.056 [20405] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.056 [20405] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.056 [20405] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.056 [20405] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.056 [20405] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.056 [20405] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.056 [20405] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.056 [20405] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.056 [20405] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.056 [20405] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.056 [20405] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.056 [20405] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.057 [20405] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.057 [20405] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.057 [20405] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.057 [20405] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.057 [20405] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.057 [20405] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.057 [20405] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.057 [20405] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.057 [20405] <2> read_client: dname=OAA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.057 [20405] <2> read_client: dname=COA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.057 [20405] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.057 [20405] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.063 [20405] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.063 [20405] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.063 [20405] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.063 [20405] <2> read_client: dname=OAA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.063 [20405] <2> read_client: dname=COA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.063 [20405] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.063 [20405] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.063 [20405] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.064 [20405] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.064 [20405] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.064 [20405] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.064 [20405] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.064 [20405] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.064 [20405] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.064 [20405] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.064 [20405] <2> read_client: dname=OAA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.064 [20405] <2> read_client: dname=COA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.064 [20405] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.064 [20405] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.064 [20405] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.064 [20405] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.064 [20405] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.064 [20405] <2> read_client: dname=OAA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.064 [20405] <2> read_client: dname=COA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.064 [20405] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.064 [20405] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.064 [20405] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.064 [20405] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.064 [20405] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.064 [20405] <2> read_client: dname=OAA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.064 [20405] <2> read_client: dname=COA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.064 [20405] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.064 [20405] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.064 [20405] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.064 [20405] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.064 [20405] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.064 [20405] <2> read_client: dname=OAA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.064 [20405] <2> read_client: dname=COA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.064 [20405] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.064 [20405] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.064 [20405] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.064 [20405] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.064 [20405] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.064 [20405] <2> read_client: dname=OAA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.064 [20405] <2> read_client: dname=COA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.064 [20405] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.064 [20405] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.064 [20405] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.064 [20405] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.064 [20405] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.064 [20405] <2> read_client: dname=OAA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.064 [20405] <2> read_client: dname=COA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.064 [20405] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.064 [20405] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.064 [20405] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.064 [20405] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.064 [20405] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.064 [20405] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.064 [20405] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.069 [20405] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.069 [20405] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.069 [20405] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.069 [20405] <2> read_client: dname=OAA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.069 [20405] <2> read_client: dname=COA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.069 [20405] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.069 [20405] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.069 [20405] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.069 [20405] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.069 [20405] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.069 [20405] <2> read_client: dname=OAA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.069 [20405] <2> read_client: dname=COA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.069 [20405] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.069 [20405] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.069 [20405] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.069 [20405] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.069 [20405] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.069 [20405] <2> read_client: dname=OAA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.069 [20405] <2> read_client: dname=COA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.069 [20405] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.069 [20405] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.070 [20405] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.070 [20405] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.070 [20405] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.070 [20405] <2> read_client: dname=OAA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.070 [20405] <2> read_client: dname=COA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.070 [20405] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.070 [20405] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.070 [20405] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.070 [20405] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.070 [20405] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.070 [20405] <2> read_client: dname=OAA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.070 [20405] <2> read_client: dname=COA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.070 [20405] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.070 [20405] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.070 [20405] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.070 [20405] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.070 [20405] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.070 [20405] <2> read_client: dname=OAA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.070 [20405] <2> read_client: dname=COA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.070 [20405] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.070 [20405] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.075 [20405] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.075 [20405] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.075 [20405] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.075 [20405] <2> read_client: dname=OAA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.075 [20405] <2> read_client: dname=COA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.075 [20405] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.075 [20405] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.075 [20405] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.075 [20405] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.075 [20405] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.075 [20405] <2> read_client: dname=OAA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.075 [20405] <2> read_client: dname=COA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.075 [20405] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.075 [20405] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.075 [20405] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.075 [20405] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.075 [20405] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.075 [20405] <2> read_client: dname=OAA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.075 [20405] <2> read_client: dname=COA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.075 [20405] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.075 [20405] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.075 [20405] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.075 [20405] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.075 [20405] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.075 [20405] <2> read_client: dname=OAA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.075 [20405] <2> read_client: dname=COA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.075 [20405] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.075 [20405] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.079 [20405] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.079 [20405] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.079 [20405] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.079 [20405] <2> read_client: dname=OAA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.079 [20405] <2> read_client: dname=COA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.079 [20405] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.079 [20405] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.079 [20405] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.079 [20405] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.079 [20405] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.079 [20405] <2> read_client: dname=OAA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.079 [20405] <2> read_client: dname=COA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.079 [20405] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.079 [20405] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.079 [20405] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.079 [20405] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.079 [20405] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.080 [20405] <2> read_client: dname=OAA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.080 [20405] <2> read_client: dname=COA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.080 [20405] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.080 [20405] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.083 [20405] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.083 [20405] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.083 [20405] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.083 [20405] <2> read_client: dname=OAA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.083 [20405] <2> read_client: dname=COA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.083 [20405] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.083 [20405] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.083 [20405] <2> read_client: dname=CO_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.083 [20405] <2> read_client: dname=host_info, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.083 [20405] <2> read_client: dname=OA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.083 [20405] <2> read_client: dname=OAA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.083 [20405] <2> read_client: dname=COA_0, offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.083 [20405] <2> read_client: dname=., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.083 [20405] <2> read_client: dname=.., offline=0, online_at=0 offline_at=0 offlineres=0 onlineres_at=0 offlineres_at=0 08:32:40.084 [20405] <2> bpclient: EXIT status = 0 08:32:40.290 [20433] <2> bpretlevel: VERBOSE = 0 08:32:40.291 [20433] <2> bpretlevel: INITIATING: NetBackup 7.7.2 created: 2016011116 08:32:40.291 [20433] <2> logparams: -M MASTER_SERVER-j 08:32:40.295 [20433] <2> bpretlevel: EXIT status = 0 08:32:40.531 [20457] <2> bpconfig: INITIATING: version NetBackup 7.7.2 created: 2016011116 08:32:40.531 [20457] <2> logparams: -M MASTER_SERVER-l 08:32:40.531 [20457] <2> bpconfig: VERBOSE = 0 08:32:40.535 [20457] <2> bpconfig: EXIT status = 0 08:32:40.734 [20473] <2> nbemmcmd: VERBOSE = 0 08:32:40.737 [20473] <8> vnet_get_user_credential_path: [vnet_vxss.c:1474] status 35 0x23 08:32:40.737 [20473] <8> vnet_check_user_certificate: [vnet_vxss_helper.c:3643] vnet_get_user_credential_path failed 35 0x23 08:32:40.737 [20473] <2> : NBEMMCMD -listsettings -brief -emmname MASTER_SERVER-emmport 1556 -machinename mstr-nbkp-srv 08:32:40.770 [20473] <2> Orb::init: Enabling ORBNativeCharCodeSet UTF-8(Orb.cpp:801) 08:32:40.771 [20473] <2> Orb::init: initializing ORB EMMlib_Orb with: nbemmcmd -ORBSvcConfDirective "-ORBDottedDecimalAddresses 0" -ORBSvcConfDirective "static Resource_Factory '-ORBNativeCharCodeSet UTF-8'" -ORBSvcConfDirective "static PBXIOP_Factory '-enable_keepalive'" -ORBSvcConfDirective "static EndpointSelectorFactory ''" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory PBXIOP_Factory'" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory IIOP_Factory'" -ORBDefaultInitRef '' -ORBSvcConfDirective "static PBXIOP_Evaluator_Factory '-orb EMMlib_Orb'" -ORBSvcConfDirective "static Resource_Factory '-ORBConnectionCacheMax 1024 '" -ORBSvcConf /dev/null -ORBSvcConfDirective "static Server_Strategy_Factory '-ORBMaxRecvGIOPPayloadSize 268435456'"(Orb.cpp:1015) 08:32:40.773 [20473] <2> Orb::init: caching EndpointSelectorFactory(Orb.cpp:1029) 08:32:40.773 [20473] <2> Orb::setOrbConnectTimeout: timeout seconds: 60(Orb.cpp:1380) 08:32:40.773 [20473] <2> Orb::setOrbRequestTimeout: timeout seconds: 1800(Orb.cpp:1389) 08:34:09.088 [28060] <2> bpgetconfig: INITIATING: version NetBackup 7.7.2 created: 2016011116 08:34:09.088 [28060] <2> logparams: /usr/openv/netbackup/bin/admincmd/bpgetconfig 08:34:09.089 [28060] <2> bpgetconfig: VERBOSE = 0 08:34:09.098 [28060] <8> vnet_get_user_credential_path: [vnet_vxss.c:1474] status 35 0x23 08:34:09.098 [28060] <8> vnet_check_user_certificate: [vnet_vxss_helper.c:3643] vnet_get_user_credential_path failed 35 0x23 08:49:33.614 [16060] <2> bpgetconfig: INITIATING: version NetBackup 7.7.2 created: 2016011116 08:49:33.614 [16060] <2> logparams: /usr/openv/netbackup/bin/admincmd/bpgetconfig 08:49:33.614 [16060] <2> bpgetconfig: VERBOSE = 0 08:49:33.624 [16060] <8> vnet_get_user_credential_path: [vnet_vxss.c:1474] status 35 0x23 08:49:33.624 [16060] <8> vnet_check_user_certificate: [vnet_vxss_helper.c:3643] vnet_get_user_credential_path failed 35 0x23