13:11:04.798 [13164] <2> SetMaxDataLimit: maximum data size: current=-1 max=-1 13:11:04.798 [13164] <2> initialize: fd values STDOUTSOCK=3 STDERRSOCK=4 13:11:04.803 [13164] <2> bptm: INITIATING (VERBOSE = 5): -w -c lonbfbelvis1.corp.ad.timeinc.com -dpath BFB-VMWARE-OST -stunit BFB-VMWARE-OST -cl ELVIS1-SNAP-TEST -bt 1389618685 -b lonbfbelvis1.corp.ad.timeinc.com_1389618685 -st 0 -cj 1 -reqid -1389612853 -jm -brm -hostname lonbfbelvis1.corp.ad.timeinc.com -ru root -rclnt lonbfbelvis1.corp.ad.timeinc.com -rclnthostname lonbfbelvis1.corp.ad.timeinc.com -rl 0 -rp 604800 -sl Weekly-Full -ct 29 -maxfrag 524288 -eari 0 -v -mediasvr lonbfbnbumedia.ipcmedia.com -ifr -no_callback -connect_options 0x01010100 -jobid 233793 -jobgrpid 233792 -masterversion 750000 -bpbrm_shm_id 4915206 -blks_per_buffer 512 13:11:04.803 [13164] <2> main: bptm.c.1591: maximum fragment size is 524288000 Kbytes 13:11:04.803 [13164] <2> bptm: PORT_STATUS = 0x01010100 13:11:04.803 [13164] <2> insert_if_not_dup: [vnet_addrinfo.c:5724] ignoring IPv6 link local 0 0x0 13:11:04.803 [13164] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 13:11:04.803 [13164] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 13:11:04.803 [13164] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 13:11:04.803 [13164] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 13:11:04.803 [13164] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 13:11:04.803 [13164] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 13:11:04.804 [13164] <2> async_connect: [vnet_connect.c:1477] connect in progress 1 0x1 13:11:04.804 [13164] <2> vnet_pbxConnect: pbxConnectEx Succeeded 13:11:04.804 [13164] <2> do_pbx_service: [vnet_connect.c:2116] via PBX bpjobd CONNECT FROM 10.132.113.128.35696 TO 10.132.112.22.1556 fd = 1 13:11:04.804 [13164] <2> async_connect: [vnet_connect.c:1644] connect async CONNECT FROM 10.132.113.128.35696 TO 10.132.112.22.1556 fd = 1 13:11:04.805 [13164] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO bfbackup.ipcmedia.com 10.132.112.22 bpjobd VIA pbx 13:11:04.805 [13164] <2> job_connect: SO_KEEPALIVE set on socket 1 for client bfbackup.ipcmedia.com 13:11:04.805 [13164] <2> logconnections: BPJOBD CONNECT FROM 10.132.113.128.35696 TO 10.132.112.22.1556 fd = 1 13:11:04.805 [13164] <2> job_authenticate_connection: ignoring VxSS authentication check for now... 13:11:04.805 [13164] <2> job_connect: Connected to the host bfbackup.ipcmedia.com contype 53 jobid <233793> socket <1> 13:11:04.805 [13164] <2> job_connect: Connected on port 35696 13:11:04.805 [13164] <2> set_job_details: Tfile (233793): LOG 1389618664 4 bptm 13164 start 13:11:04.805 [13164] <2> send_job_file: job ID 233793, ftype = 3 msg len = 34, msg = LOG 1389618664 4 bptm 13164 start 13:11:04.805 [13164] <8> vnet_same_host_and_update: [vnet_addrinfo.c:2829] name2 is empty 0 0x0 13:11:04.805 [13164] <4> bptm: emmserver_name = bfbackup 13:11:04.805 [13164] <4> bptm: emmserver_port = 1556 13:11:04.813 [13164] <2> Orb::setDebugLevelFromVxul: Orb logging configuration level set to 0(Orb.cpp:2298) 13:11:04.813 [13164] <2> emmlib_initialize: (-) Connection attempt #<0> 13:11:04.813 [13164] <2> emmlib_initializeEx: (-) Connecting to the Server Port <1556>, App OrbOpt 13:11:04.813 [13164] <2> Evictor::Evictor(): +++ ENTERING +++ : obj = 0xbc1a6e0 (../Evictor.cpp:198) 13:11:04.813 [13164] <2> Evictor::Evictor(): --- EXITING --- : obj = 0xbc1a6e0 (../Evictor.cpp:198) 13:11:04.813 [13164] <2> Orb::Orb: +++ ENTERING +++ : obj = 0xbc1a490 (../Orb.cpp:320) 13:11:04.814 [13164] <2> SetMaxFileNum: current max fileno: 1024, max fileno hard limit: 1024(SetMaxFileNum.cpp:34) 13:11:04.814 [13164] <2> SetMaxFileNum: successfully set max fileno: current=1024, max=1024(SetMaxFileNum.cpp:90) 13:11:04.814 [13164] <2> Orb::Orb: --- EXITING --- : obj = 0xbc1a490 (../Orb.cpp:320) 13:11:04.814 [13164] <2> Orb::init: +++ ENTERING +++ : obj = 0xbc1a490 (../Orb.cpp:501) 13:11:04.818 [13164] <2> Orb::setDebugLevelFromVxul: Orb logging configuration level set to 0(Orb.cpp:2298) 13:11:04.818 [13164] <2> Orb::init: checking if NBAC is enabled(Orb.cpp:518) 13:11:04.818 [13164] <2> BpConfigHolder::BpConfigHolder: +++ ENTERING +++ : obj = 0xbc18128 (../BpConf.cpp:34) 13:11:04.818 [13164] <2> BpConfigHolder::reload: +++ ENTERING +++ : obj = 0xbc18128 (../BpConf.cpp:46) 13:11:04.818 [13164] <2> BpConfigHolder::reload: --- EXITING --- : obj = 0xbc18128 (../BpConf.cpp:46) 13:11:04.818 [13164] <2> BpConfigHolder::BpConfigHolder: --- EXITING --- : obj = 0xbc18128 (../BpConf.cpp:34) 13:11:04.818 [13164] <2> EndpointSelectorFactory::EndpointSelectorFactory: +++ ENTERING +++ : obj = 0xbc1b920 (../Endpoint_Selector.cpp:1934) 13:11:04.818 [13164] <2> EndpointSelector_R2::EndpointSelector_R2: +++ ENTERING +++ : obj = 0xbc1b980 (../Endpoint_Selector.cpp:362) 13:11:04.818 [13164] <2> EndpointSelector_R2::updateConfMembers: epsr2: bp.conf LastUpd=1389618602 bp.conf LastLook=0(Endpoint_Selector.cpp:403) 13:11:04.818 [13164] <2> EndpointSelector_R2::updateConfMembers: epsr2: bp.conf changed since last look, reloading(Endpoint_Selector.cpp:407) 13:11:04.818 [13164] <2> EndpointSelector_R2::updateConfMembers: epsr2: tardy lookup log time value set to 5 seconds(Endpoint_Selector.cpp:442) 13:11:04.818 [13164] <2> EndpointSelector_R2::updateConfMembers: epsr2: USE_VXSS = PROHIBITED(Endpoint_Selector.cpp:453) 13:11:04.818 [13164] <2> EndpointSelector_R2::EndpointSelector_R2: --- EXITING --- : obj = 0xbc1b980 (../Endpoint_Selector.cpp:362) 13:11:04.818 [13164] <2> EndpointSelectorFactory::EndpointSelectorFactory: Endpoint Selector model: 7.1 Flags: 0(Endpoint_Selector.cpp:1981) 13:11:04.818 [13164] <2> EndpointSelectorFactory::EndpointSelectorFactory: --- EXITING --- : obj = 0xbc1b920 (../Endpoint_Selector.cpp:1934) 13:11:04.818 [13164] <2> InsecureEndpointSelectorFactory::InsecureEndpointSelectorFactory: +++ ENTERING +++ : obj = 0xbc1bb10 (../Endpoint_Selector.cpp:2028) 13:11:04.818 [13164] <2> EndpointSelector_R2::EndpointSelector_R2: +++ ENTERING +++ : obj = 0xbc1bb70 (../Endpoint_Selector.cpp:362) 13:11:04.818 [13164] <2> EndpointSelector_R2::updateConfMembers: epsr2: bp.conf LastUpd=1389618602 bp.conf LastLook=0(Endpoint_Selector.cpp:403) 13:11:04.818 [13164] <2> EndpointSelector_R2::updateConfMembers: epsr2: bp.conf changed since last look, reloading(Endpoint_Selector.cpp:407) 13:11:04.818 [13164] <2> EndpointSelector_R2::updateConfMembers: epsr2: tardy lookup log time value set to 5 seconds(Endpoint_Selector.cpp:442) 13:11:04.818 [13164] <2> EndpointSelector_R2::updateConfMembers: epsr2: USE_VXSS = PROHIBITED(Endpoint_Selector.cpp:453) 13:11:04.818 [13164] <2> EndpointSelector_R2::EndpointSelector_R2: --- EXITING --- : obj = 0xbc1bb70 (../Endpoint_Selector.cpp:362) 13:11:04.818 [13164] <2> InsecureEndpointSelectorFactory::InsecureEndpointSelectorFactory: Endpoint Selector model: 7.1 Flags: 0 [Ignoring BPCONF server list](Endpoint_Selector.cpp:2076) 13:11:04.818 [13164] <2> InsecureEndpointSelectorFactory::InsecureEndpointSelectorFactory: --- EXITING --- : obj = 0xbc1bb10 (../Endpoint_Selector.cpp:2028) 13:11:04.818 [13164] <2> checkService: found service: UTF16_BOM_Factory(Orb.cpp:494) 13:11:04.818 [13164] <2> checkService: found service: UTF8-Latin1_Factory(Orb.cpp:494) 13:11:04.818 [13164] <2> LogMaxFileNum: current max fileno: 1024, max fileno hard limit: 1024(SetMaxFileNum.cpp:113) 13:11:04.818 [13164] <2> Orb::getConfig: found service entry: name = veritas_pbx port = 1556 proto = tcp(Orb.cpp:412) 13:11:04.818 [13164] <2> Orb::getConfig: cluster_name and required_interface not defined, using ANY(Orb.cpp:464) 13:11:04.818 [13164] <2> Orb::init: Enabling ORBNativeCharCodeSet UTF-8(Orb.cpp:604) 13:11:04.818 [13164] <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:657) 13:11:04.818 [13164] <2> Orb::init: added args has value of: (Orb.cpp:787) 13:11:04.819 [13164] <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:842) 13:11:04.819 [13164] <2> NBEvaluatorFactory::init: +++ ENTERING +++ : obj = 0xbc1b560 (../SecureEvaluator.cpp:498) 13:11:04.819 [13164] <2> NBEvaluatorFactory::init: --- EXITING --- : obj = 0xbc1b560 (../SecureEvaluator.cpp:498) 13:11:04.820 [13164] <2> NBOrbInitializer::pre_init: +++ ENTERING +++ : obj = 0xbc1c2e0 (../NBOrbInitializer.cpp:45) 13:11:04.820 [13164] <2> NBOrbInitializer::pre_init: --- EXITING --- : obj = 0xbc1c2e0 (../NBOrbInitializer.cpp:45) 13:11:04.820 [13164] <2> NBOrbInitializer::post_init: +++ ENTERING +++ : obj = 0xbc1c2e0 (../NBOrbInitializer.cpp:53) 13:11:04.820 [13164] <2> NBOrbInitializer::post_init: Adding interceptors(NBOrbInitializer.cpp:74) 13:11:04.820 [13164] <2> NBIORInterceptor::NBIORInterceptor: Interceptor Name: EMMlib_Orb_EMMlib_Orb(NBIORInterceptor.cpp:47) 13:11:04.820 [13164] <2> NBOrbInitializer::post_init: --- EXITING --- : obj = 0xbc1c2e0 (../NBOrbInitializer.cpp:53) 13:11:04.820 [13164] <2> Orb::init: caching EndpointSelectorFactory(Orb.cpp:857) 13:11:04.820 [13164] <2> Orb::setOrbTimeoutPolicy: setting ORB connection timeout policy: tv = 300(Orb.cpp:1521) 13:11:04.821 [13164] <2> Orb::activate: +++ ENTERING +++ : obj = 0xbc1a490 (../Orb.cpp:1248) 13:11:04.821 [13164] <2> Orb::activate: Client Orb called activate; Ignored(Orb.cpp:1253) 13:11:04.821 [13164] <2> Orb::activate: --- EXITING --- : obj = 0xbc1a490 (../Orb.cpp:1248) 13:11:04.821 [13164] <2> Orb::init: --- EXITING --- : obj = 0xbc1a490 (../Orb.cpp:501) 13:11:04.821 [13164] <2> Orb::setOrbTimeoutPolicy: setting ORB connection timeout policy: tv = 60(Orb.cpp:1521) 13:11:04.821 [13164] <2> Orb::setOrbConnectTimeout: timeout seconds: 60(Orb.cpp:1498) 13:11:04.821 [13164] <2> emmlib_initializeEx: (-) Setting connect timeout to <60> sec 13:11:04.821 [13164] <2> Orb::setOrbTimeoutPolicy: setting ORB request timeout policy: tv = 1800(Orb.cpp:1521) 13:11:04.821 [13164] <2> Orb::setOrbRequestTimeout: timeout seconds: 1800(Orb.cpp:1507) 13:11:04.821 [13164] <2> emmlib_initializeEx: (-) Setting request timeout to <1800> sec 13:11:04.821 [13164] <2> Orb::connectToObject: Connection attempt #1 mW:60 rW:0(Orb.cpp:1184) 13:11:04.821 [13164] <2> Orb::createInsecureObjectRef: +++ ENTERING +++ : obj = 0xbc1a490 (../Orb.cpp:1449) 13:11:04.821 [13164] <2> Orb::createInsecureObjectRef: corbaloc being used is: corbaloc:pbxiop:bfbackup:1556:EMM/EMMServer(Orb.cpp:1457) 13:11:04.821 [13164] <2> Orb::createInsecureObjectRef: --- EXITING --- : obj = 0xbc1a490 (../Orb.cpp:1449) 13:11:04.821 [13164] <2> Orb::connectToObjectOnce: attempt to create insecure connection(Orb.cpp:1051) 13:11:04.821 [13164] <2> EndpointSelectorFactory::get_selector: epsr2: got 7.x EPS(Endpoint_Selector.cpp:2007) 13:11:04.821 [13164] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:530) 13:11:04.821 [13164] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Evaluating 1 endpoints(Endpoint_Selector.cpp:631) 13:11:04.821 [13164] <2> ConnectList::search: host bfbackup not found in cache(ConnectList.cpp:116) 13:11:04.821 [13164] <2> VxssContext::useVxss: epsr2: Setting up useVxss=2(Endpoint_Selector.cpp:157) 13:11:04.821 [13164] <2> VxssContext::begin: epsr2: Setup context, passes. vxss=2 passes=1(Endpoint_Selector.cpp:187) 13:11:04.821 [13164] <2> VxssContext::end: epsr2: all done? passes=1 curridx=0(Endpoint_Selector.cpp:193) 13:11:04.821 [13164] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: pass: 0 secure_mode:2/0(Endpoint_Selector.cpp:778) 13:11:04.821 [13164] <2> ConnectList::search: host bfbackup not found in cache(ConnectList.cpp:116) 13:11:04.821 [13164] <2> EndpointSelector_R2::evaluateEndpoint: epsr2: PBXIOP endpoint host: bfbackup(Endpoint_Selector.cpp:1471) 13:11:04.822 [13164] <2> ConnectList::search: host 10.132.112.22 not found in cache(ConnectList.cpp:116) 13:11:04.822 [13164] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 13:11:04.822 [13164] <2> EndpointSelector_R2::extractByAddressFamily: epsr2: address_binding: 10.132.112.22: ANY(Endpoint_Selector.cpp:1725) 13:11:04.822 [13164] <2> EndpointSelector_R2::cachedHostLookup: epsr2: Hostname bfbackup has 1 candidate addresses(Endpoint_Selector.cpp:1654) 13:11:04.822 [13164] <2> EndpointSelector_R2::evaluateEndpoint: epsr2: Endpoint host bfbackup is not checked against server list(Endpoint_Selector.cpp:1590) 13:11:04.822 [13164] <2> EndpointSelector_R2::evaluateEndpoint: epsr2: Found 1 usable IP addresses for host bfbackup:[10.132.112.22] (Endpoint_Selector.cpp:1599) 13:11:04.822 [13164] <2> ConnectList::search: host 10.132.112.22 not found in cache(ConnectList.cpp:116) 13:11:04.822 [13164] <2> ExternalResolver::set: Resolved host_name 10.132.112.22[10.132.112.22](ExternalResolver.cpp:56) 13:11:04.822 [13164] <2> EndpointSelector_R2::performCallUsingEndpointAndSourceBinding: epsr2: Target: 10.132.112.22, source: ANY(Endpoint_Selector.cpp:976) 13:11:04.822 [13164] <2> VxssContext::updateOnFailure: epsr2: updateOnFailure?=1 useVxss=2(Endpoint_Selector.cpp:243) 13:11:04.823 [13164] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure invocation->perform_call(...) to host 10.132.112.22 SUCCESS(Endpoint_Selector.cpp:1186) 13:11:04.823 [13164] <2> ConnectList::update: added host 10.132.112.22 (1 total) as GOOD(ConnectList.cpp:211) 13:11:04.823 [13164] <2> VxssContext::end: epsr2: all done? passes=1 curridx=1(Endpoint_Selector.cpp:193) 13:11:04.825 [13164] <2> EndpointSelector_R2::forward: epsr2: Adding 1 forward profiles(Endpoint_Selector.cpp:555) 13:11:04.825 [13164] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:530) 13:11:04.825 [13164] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Evaluating 2 endpoints(Endpoint_Selector.cpp:631) 13:11:04.825 [13164] <2> ConnectList::search: host bfbackup.ipcmedia.com not found in cache(ConnectList.cpp:116) 13:11:04.825 [13164] <2> EndpointSelector_R2::evaluateNatBindings: epsr2: Target host does not advertise the address we originally connected to. Creating NAT binding of bfbackup.ipcmedia.com to 10.132.112.22 in connect list.(Endpoint_Selector.cpp:1891) 13:11:04.825 [13164] <2> EndpointSelector_R2::evaluateNatBindings: epsr2: Target host does not advertise the address we originally connected to. Creating NAT binding of 127.0.0.1 to 10.132.112.22 in connect list.(Endpoint_Selector.cpp:1891) 13:11:04.825 [13164] <2> ExternalResolver::set: Resolved host_name 10.132.112.22[10.132.112.22](ExternalResolver.cpp:56) 13:11:04.825 [13164] <2> EndpointSelector_R2::evaluateNatBindings: epsr2: Target host does not advertise the address we originally connected to. Replacing bfbackup.ipcmedia.com with 10.132.112.22 for target endpoint.(Endpoint_Selector.cpp:1899) 13:11:04.825 [13164] <2> ConnectList::search: host 10.132.112.22 found GOOD(ConnectList.cpp:89) 13:11:04.825 [13164] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Reusing endpoint, target: 10.132.112.22, source: ANY(Endpoint_Selector.cpp:687) 13:11:04.825 [13164] <2> VxssContext::updateOnFailure: epsr2: updateOnFailure?=0 useVxss=2(Endpoint_Selector.cpp:243) 13:11:04.825 [13164] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure invocation->perform_call(...) to host 10.132.112.22 SUCCESS(Endpoint_Selector.cpp:1186) 13:11:04.825 [13164] <2> ConnectList::update: updated host 10.132.112.22 as GOOD, used 0 of 3 retries(ConnectList.cpp:175) 13:11:04.825 [13164] <2> Orb::connectToObjectOnce: connection created(Orb.cpp:1069) 13:11:04.825 [13164] <2> EndpointSelectorFactory::get_selector: epsr2: got 7.x EPS(Endpoint_Selector.cpp:2007) 13:11:04.825 [13164] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:530) 13:11:04.825 [13164] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Evaluating 2 endpoints(Endpoint_Selector.cpp:631) 13:11:04.825 [13164] <2> ConnectList::search: host 10.132.112.22 found GOOD(ConnectList.cpp:89) 13:11:04.825 [13164] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 13:11:04.825 [13164] <2> EndpointSelector_R2::extractByAddressFamily: epsr2: address_binding: 10.132.112.22: ANY(Endpoint_Selector.cpp:1725) 13:11:04.825 [13164] <2> EndpointSelector_R2::cachedHostLookup: epsr2: Hostname 10.132.112.22 has 1 candidate addresses(Endpoint_Selector.cpp:1654) 13:11:04.825 [13164] <2> ConnectList::search: host 10.132.112.22 found GOOD(ConnectList.cpp:89) 13:11:04.825 [13164] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Reusing endpoint, target: 10.132.112.22, source: ANY(Endpoint_Selector.cpp:687) 13:11:04.825 [13164] <2> VxssContext::updateOnFailure: epsr2: updateOnFailure?=0 useVxss=2(Endpoint_Selector.cpp:243) 13:11:04.825 [13164] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure invocation->perform_call(...) to host 10.132.112.22 SUCCESS(Endpoint_Selector.cpp:1186) 13:11:04.826 [13164] <2> ConnectList::update: updated host 10.132.112.22 as GOOD, used 0 of 3 retries(ConnectList.cpp:175) 13:11:04.826 [13164] <4> CreateConnectionID: (-) Using existing uninitialized Connection ID 0 13:11:04.826 [13164] <4> emmlib_initializeEx: (0) Successfully initialized EMM session 13:11:04.826 [13164] <2> nbjm_media_request: Passing job control to NBJM, type WRITE/1 13:11:04.826 [13164] <2> nbjm_media_request: old_media_id = , media_id = NULL 13:11:04.826 [13164] <2> RequestInitialResources: starting 13:11:04.826 [13164] <2> RequestInitialResources: started 13:11:04.826 [13164] <2> Evictor::Evictor(): +++ ENTERING +++ : obj = 0xbc5db90 (../Evictor.cpp:198) 13:11:04.826 [13164] <2> Evictor::Evictor(): --- EXITING --- : obj = 0xbc5db90 (../Evictor.cpp:198) 13:11:04.826 [13164] <2> Orb::Orb: +++ ENTERING +++ : obj = 0xbc5d940 (../Orb.cpp:360) 13:11:04.826 [13164] <2> SetMaxFileNum: current max fileno: 1024, max fileno hard limit: 1024(SetMaxFileNum.cpp:34) 13:11:04.826 [13164] <2> SetMaxFileNum: successfully set max fileno: current=1024, max=1024(SetMaxFileNum.cpp:90) 13:11:04.826 [13164] <2> Orb::Orb: --- EXITING --- : obj = 0xbc5d940 (../Orb.cpp:360) 13:11:04.826 [13164] <2> Orb::init: +++ ENTERING +++ : obj = 0xbc5d940 (../Orb.cpp:501) 13:11:04.826 [13164] <2> Orb::init: checking if NBAC is enabled(Orb.cpp:518) 13:11:04.826 [13164] <2> checkService: found service: UTF16_BOM_Factory(Orb.cpp:494) 13:11:04.826 [13164] <2> checkService: found service: UTF8-Latin1_Factory(Orb.cpp:494) 13:11:04.826 [13164] <2> LogMaxFileNum: current max fileno: 1024, max fileno hard limit: 1024(SetMaxFileNum.cpp:113) 13:11:04.826 [13164] <2> Orb::getConfig: found service entry: name = veritas_pbx port = 1556 proto = tcp(Orb.cpp:412) 13:11:04.826 [13164] <2> Orb::getConfig: cluster_name and required_interface not defined, using ANY(Orb.cpp:464) 13:11:04.826 [13164] <2> Orb::init: Enabling ORBNativeCharCodeSet UTF-8(Orb.cpp:604) 13:11:04.826 [13164] <2> Orb::init: substituting orb name of: Default_DAEMON_Orb(Orb.cpp:638) 13:11:04.826 [13164] <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:657) 13:11:04.826 [13164] <2> Orb::init: Created anon service name: NB_13164_708988832(Orb.cpp:714) 13:11:04.826 [13164] <2> Orb::init: endpointvalue is : pbxiop://1556:NB_13164_708988832(Orb.cpp:731) 13:11:04.826 [13164] <2> Orb::init: added args has value of: (Orb.cpp:787) 13:11:04.826 [13164] <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_13164_708988832 -ORBSvcConf /dev/null -ORBSvcConfDirective "static Server_Strategy_Factory '-ORBMaxRecvGIOPPayloadSize 268435456'"(Orb.cpp:842) 13:11:04.827 [13164] <2> NBOrbInitializer::pre_init: +++ ENTERING +++ : obj = 0xbc1c2e0 (../NBOrbInitializer.cpp:45) 13:11:04.827 [13164] <2> NBOrbInitializer::pre_init: --- EXITING --- : obj = 0xbc1c2e0 (../NBOrbInitializer.cpp:45) 13:11:04.827 [13164] <2> NBOrbInitializer::pre_init: +++ ENTERING +++ : obj = 0xbc5de30 (../NBOrbInitializer.cpp:45) 13:11:04.827 [13164] <2> NBOrbInitializer::pre_init: --- EXITING --- : obj = 0xbc5de30 (../NBOrbInitializer.cpp:45) 13:11:04.827 [13164] <2> NBOrbInitializer::post_init: +++ ENTERING +++ : obj = 0xbc1c2e0 (../NBOrbInitializer.cpp:53) 13:11:04.827 [13164] <2> NBOrbInitializer::post_init: --- EXITING --- : obj = 0xbc1c2e0 (../NBOrbInitializer.cpp:53) 13:11:04.827 [13164] <2> NBOrbInitializer::post_init: +++ ENTERING +++ : obj = 0xbc5de30 (../NBOrbInitializer.cpp:53) 13:11:04.827 [13164] <2> NBOrbInitializer::post_init: Adding interceptors(NBOrbInitializer.cpp:74) 13:11:04.827 [13164] <2> NBIORInterceptor::NBIORInterceptor: Interceptor Name: NB_13164_708988832_Default_DAEMON_Orb(NBIORInterceptor.cpp:47) 13:11:04.827 [13164] <2> NBOrbInitializer::post_init: --- EXITING --- : obj = 0xbc5de30 (../NBOrbInitializer.cpp:53) 13:11:04.827 [13164] <2> Orb::init: caching EndpointSelectorFactory(Orb.cpp:857) 13:11:04.827 [13164] <2> Orb::setOrbTimeoutPolicy: setting ORB connection timeout policy: tv = 300(Orb.cpp:1521) 13:11:04.827 [13164] <2> Orb::activate: +++ ENTERING +++ : obj = 0xbc5d940 (../Orb.cpp:1248) 13:11:04.827 [13164] <2> NBEvaluatorFactory::make_evaluator(): +++ ENTERING +++ : obj = 0xbc1b560 (../SecureEvaluator.cpp:516) 13:11:04.827 [13164] <2> NBEvaluatorFactory::make_evaluator(): --- EXITING --- : obj = 0xbc1b560 (../SecureEvaluator.cpp:516) 13:11:04.827 [13164] <2> NBEvaluatorFactory::make_credential_evaluator(): +++ ENTERING +++ : obj = 0xbc1b560 (../SecureEvaluator.cpp:536) 13:11:04.827 [13164] <2> NBEvaluatorFactory::make_credential_evaluator(): --- EXITING --- : obj = 0xbc1b560 (../SecureEvaluator.cpp:536) 13:11:04.869 [13164] <2> file_to_cache_item: [vnet_addrinfo.c:6567] get_cache_item_path() failed 26 0x1a 13:11:04.887 [13164] <2> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1632] found via getaddrinfo NAME=fe80::6ab5:99ff:fe76:38b6%15 SVC=NULL 13:11:04.887 [13164] <8> copy_addrinfo: [vnet_addrinfo.c:3701] no valid addresses to copy 0 0x0 13:11:04.887 [13164] <8> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1638] copy_addrinfo() failed NAME=fe80::6ab5:99ff:fe76:38b6%15 STAT=6 13:11:04.887 [13164] <8> vnet_cached_getaddrinfo: [vnet_addrinfo.c:1275] vnet_cached_getaddrinfo_and_update() failed 6 0x6 13:11:04.887 [13164] <2> ExternalResolver::set: Could not resolve host_name: fe80::6ab5:99ff:fe76:38b6%15 status/errval: 6(0)(ExternalResolver.cpp:41) 13:11:04.906 [13164] <2> NBIORInterceptor::establish_components: +++ ENTERING +++ : obj = 0xbc84a50 (../NBIORInterceptor.cpp:69) 13:11:04.906 [13164] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 13:11:04.906 [13164] <2> NBIORInterceptor::establish_components: Encoding IP Address [127.0.0.1] in IOR(NBIORInterceptor.cpp:117) 13:11:04.906 [13164] <2> NBIORInterceptor::establish_components: --- EXITING --- : obj = 0xbc84a50 (../NBIORInterceptor.cpp:69) 13:11:04.906 [13164] <2> Orb::activate: --- EXITING --- : obj = 0xbc5d940 (../Orb.cpp:1248) 13:11:04.906 [13164] <2> Orb::init: --- EXITING --- : obj = 0xbc5d940 (../Orb.cpp:501) 13:11:04.906 [13164] <2> Orb::connectToObject: Connection attempt #1 mW:30 rW:0(Orb.cpp:1184) 13:11:04.906 [13164] <2> Orb::createInsecureObjectRef: +++ ENTERING +++ : obj = 0xbc5d940 (../Orb.cpp:1449) 13:11:04.906 [13164] <2> Orb::createInsecureObjectRef: corbaloc being used is: corbaloc:pbxiop:bfbackup.ipcmedia.com:1556:nbjm/JOBINST(Orb.cpp:1457) 13:11:04.906 [13164] <2> Orb::createInsecureObjectRef: --- EXITING --- : obj = 0xbc5d940 (../Orb.cpp:1449) 13:11:04.906 [13164] <2> Orb::connectToObjectOnce: attempt to create insecure connection(Orb.cpp:1051) 13:11:04.906 [13164] <2> EndpointSelectorFactory::get_selector: epsr2: got 7.x EPS(Endpoint_Selector.cpp:2007) 13:11:04.906 [13164] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:530) 13:11:04.906 [13164] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Evaluating 1 endpoints(Endpoint_Selector.cpp:631) 13:11:04.906 [13164] <2> ConnectList::search: host bfbackup.ipcmedia.com not found in cache(ConnectList.cpp:116) 13:11:04.906 [13164] <2> ExternalResolver::set: Resolved host_name 10.132.112.22[10.132.112.22](ExternalResolver.cpp:56) 13:11:04.906 [13164] <2> EndpointSelector_R2::evaluateNatBindings: epsr2: Target host does not advertise the address we originally connected to. Replacing bfbackup.ipcmedia.com with 10.132.112.22 in connect list.(Endpoint_Selector.cpp:1915) 13:11:04.906 [13164] <2> ConnectList::search: host 10.132.112.22 found GOOD(ConnectList.cpp:89) 13:11:04.906 [13164] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Reusing endpoint, target: 10.132.112.22, source: ANY(Endpoint_Selector.cpp:687) 13:11:04.907 [13164] <2> VxssContext::updateOnFailure: epsr2: updateOnFailure?=0 useVxss=2(Endpoint_Selector.cpp:243) 13:11:04.907 [13164] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure invocation->perform_call(...) to host 10.132.112.22 SUCCESS(Endpoint_Selector.cpp:1186) 13:11:04.907 [13164] <2> ConnectList::update: updated host 10.132.112.22 as GOOD, used 0 of 3 retries(ConnectList.cpp:175) 13:11:04.909 [13164] <2> EndpointSelector_R2::forward: epsr2: Adding 1 forward profiles(Endpoint_Selector.cpp:555) 13:11:04.909 [13164] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:530) 13:11:04.909 [13164] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Evaluating 2 endpoints(Endpoint_Selector.cpp:631) 13:11:04.909 [13164] <2> ConnectList::search: host bfbackup.ipcmedia.com not found in cache(ConnectList.cpp:116) 13:11:04.909 [13164] <2> ExternalResolver::set: Resolved host_name 10.132.112.22[10.132.112.22](ExternalResolver.cpp:56) 13:11:04.909 [13164] <2> EndpointSelector_R2::evaluateNatBindings: epsr2: Target host does not advertise the address we originally connected to. Replacing bfbackup.ipcmedia.com with 10.132.112.22 in connect list.(Endpoint_Selector.cpp:1915) 13:11:04.909 [13164] <2> ConnectList::search: host 10.132.112.22 found GOOD(ConnectList.cpp:89) 13:11:04.909 [13164] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Reusing endpoint, target: 10.132.112.22, source: ANY(Endpoint_Selector.cpp:687) 13:11:04.909 [13164] <2> VxssContext::updateOnFailure: epsr2: updateOnFailure?=0 useVxss=2(Endpoint_Selector.cpp:243) 13:11:04.909 [13164] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure invocation->perform_call(...) to host 10.132.112.22 SUCCESS(Endpoint_Selector.cpp:1186) 13:11:04.909 [13164] <2> ConnectList::update: updated host 10.132.112.22 as GOOD, used 0 of 3 retries(ConnectList.cpp:175) 13:11:04.909 [13164] <2> Orb::connectToObjectOnce: connection created(Orb.cpp:1069) 13:11:04.909 [13164] <2> EndpointSelectorFactory::get_selector: epsr2: got 7.x EPS(Endpoint_Selector.cpp:2007) 13:11:04.909 [13164] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:530) 13:11:04.909 [13164] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Evaluating 2 endpoints(Endpoint_Selector.cpp:631) 13:11:04.909 [13164] <2> ConnectList::search: host 10.132.112.22 found GOOD(ConnectList.cpp:89) 13:11:04.909 [13164] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 13:11:04.909 [13164] <2> EndpointSelector_R2::extractByAddressFamily: epsr2: address_binding: 10.132.112.22: ANY(Endpoint_Selector.cpp:1725) 13:11:04.909 [13164] <2> EndpointSelector_R2::cachedHostLookup: epsr2: Hostname 10.132.112.22 has 1 candidate addresses(Endpoint_Selector.cpp:1654) 13:11:04.909 [13164] <2> ConnectList::search: host 10.132.112.22 found GOOD(ConnectList.cpp:89) 13:11:04.909 [13164] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Reusing endpoint, target: 10.132.112.22, source: ANY(Endpoint_Selector.cpp:687) 13:11:04.909 [13164] <2> VxssContext::updateOnFailure: epsr2: updateOnFailure?=0 useVxss=2(Endpoint_Selector.cpp:243) 13:11:04.909 [13164] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure invocation->perform_call(...) to host 10.132.112.22 SUCCESS(Endpoint_Selector.cpp:1186) 13:11:04.909 [13164] <2> ConnectList::update: updated host 10.132.112.22 as GOOD, used 0 of 3 retries(ConnectList.cpp:175) 13:11:04.910 [13164] <2> packageInitialResourceRequest: started 13:11:04.910 [13164] <2> packageInitialResourceRequest: retVal = 0 13:11:04.910 [13164] <2> RequestInitialResources: setting up callback and calling requestResources 13:11:04.910 [13164] <2> Orb::createObjectRef: creating object reference(Orb.cpp:2208) 13:11:04.910 [13164] <2> logResourceReq: req.versionId : 1 13:11:04.910 [13164] <2> logResourceReq: req.jobId : -1389612853 13:11:04.910 [13164] <2> logResourceReq: req.startedViaJobManager : true 13:11:04.910 [13164] <2> logResourceReq: req.consumer : true 13:11:04.910 [13164] <2> logResourceReq: InitialSTUSelect - 13:11:04.910 [13164] <2> logResourceReq: reqNum : 0 13:11:04.910 [13164] <2> logResourceReq: preferredMediaServer : 13:11:04.910 [13164] <2> logResourceReq: requiredMediaServer : lonbfbnbumedia.ipcmedia.com 13:11:04.910 [13164] <2> logResourceReq: storageUnit : BFB-VMWARE-OST 13:11:04.910 [13164] <2> logResourceReq: mustBeNdmp : false 13:11:04.910 [13164] <2> logResourceReq: getMaxFreeSpace : false 13:11:04.910 [13164] <2> logResourceReq: mediaPool : 13:11:04.910 [13164] <2> logResourceReq: shareGroup : 13:11:04.910 [13164] <2> logResourceReq: requestFlags : 0x0 13:11:04.910 [13164] <2> logResourceReq: NDMPHostName : 13:11:04.910 [13164] <2> logResourceReq: retentionLevel : 0 13:11:04.911 [13164] <2> logResourceReq: mustUseLocalMediaServer : false 13:11:04.911 [13164] <2> logResourceReq: failOnError : false 13:11:04.911 [13164] <2> logResourceReq: mpxRequired : false 13:11:04.911 [13164] <2> logResourceReq: client : lonbfbelvis1.corp.ad.timeinc.com 13:11:04.911 [13164] <2> logResourceReq: usageType : 1 13:11:04.911 [13164] <2> EndpointSelectorFactory::get_selector: epsr2: got 7.x EPS(Endpoint_Selector.cpp:2007) 13:11:04.911 [13164] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:530) 13:11:04.911 [13164] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Evaluating 2 endpoints(Endpoint_Selector.cpp:631) 13:11:04.911 [13164] <2> ConnectList::search: host 10.132.112.22 found GOOD(ConnectList.cpp:89) 13:11:04.911 [13164] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 13:11:04.911 [13164] <2> EndpointSelector_R2::extractByAddressFamily: epsr2: address_binding: 10.132.112.22: ANY(Endpoint_Selector.cpp:1725) 13:11:04.911 [13164] <2> EndpointSelector_R2::cachedHostLookup: epsr2: Hostname 10.132.112.22 has 1 candidate addresses(Endpoint_Selector.cpp:1654) 13:11:04.911 [13164] <2> ConnectList::search: host 10.132.112.22 found GOOD(ConnectList.cpp:89) 13:11:04.911 [13164] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Reusing endpoint, target: 10.132.112.22, source: ANY(Endpoint_Selector.cpp:687) 13:11:04.911 [13164] <2> VxssContext::updateOnFailure: epsr2: updateOnFailure?=0 useVxss=2(Endpoint_Selector.cpp:243) 13:11:04.911 [13164] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure invocation->perform_call(...) to host 10.132.112.22 SUCCESS(Endpoint_Selector.cpp:1186) 13:11:04.911 [13164] <2> ConnectList::update: updated host 10.132.112.22 as GOOD, used 0 of 3 retries(ConnectList.cpp:175) 13:11:04.912 [13164] <2> RequestInitialResources: BpxmiV->requestResourcesWithTimeout returns 0 13:11:04.912 [13164] <2> EndpointSelectorFactory::get_selector: epsr2: got 7.x EPS(Endpoint_Selector.cpp:2007) 13:11:04.912 [13164] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:530) 13:11:04.912 [13164] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Evaluating 2 endpoints(Endpoint_Selector.cpp:631) 13:11:04.912 [13164] <2> ConnectList::search: host 10.132.112.22 found GOOD(ConnectList.cpp:89) 13:11:04.912 [13164] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 13:11:04.912 [13164] <2> EndpointSelector_R2::extractByAddressFamily: epsr2: address_binding: 10.132.112.22: ANY(Endpoint_Selector.cpp:1725) 13:11:04.912 [13164] <2> EndpointSelector_R2::cachedHostLookup: epsr2: Hostname 10.132.112.22 has 1 candidate addresses(Endpoint_Selector.cpp:1654) 13:11:04.912 [13164] <2> ConnectList::search: host 10.132.112.22 found GOOD(ConnectList.cpp:89) 13:11:04.912 [13164] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Reusing endpoint, target: 10.132.112.22, source: ANY(Endpoint_Selector.cpp:687) 13:11:04.912 [13164] <2> VxssContext::updateOnFailure: epsr2: updateOnFailure?=0 useVxss=2(Endpoint_Selector.cpp:243) 13:11:04.912 [13164] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure invocation->perform_call(...) to host 10.132.112.22 SUCCESS(Endpoint_Selector.cpp:1186) 13:11:04.912 [13164] <2> ConnectList::update: updated host 10.132.112.22 as GOOD, used 0 of 3 retries(ConnectList.cpp:175) 13:11:04.915 [13164] <2> InsecureServerEvaluator::allow_peer: +++ ENTERING +++ : obj = 0xbc1b700 (../SecureEvaluator.cpp:248) 13:11:04.916 [13164] <2> InsecureServerEvaluator::allow_peer: PeerAddress is set to bfbackup.ipcmedia.com (10.132.112.22)(SecureEvaluator.cpp:264) 13:11:04.916 [13164] <2> InsecureServerEvaluator::allow_peer: Allowing peer to connect(SecureEvaluator.cpp:403) 13:11:04.916 [13164] <2> InsecureServerEvaluator::allow_peer: --- EXITING --- : obj = 0xbc1b700 (../SecureEvaluator.cpp:248) 13:11:04.916 [13164] <2> resourcesAvailable: m_allocationSeq.length() == 1 13:11:04.916 [13164] <2> resourcesAvailable: thisAllocation->mdar.Bsr.BptmStrings.length() == 7 13:11:04.916 [13164] <2> resourcesAvailable: MEDIADB 1 85461 *NULL* 0 *NULL* 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 13:11:04.916 [13164] <2> resourcesAvailable: VOLUME 1 *NULL* 0 *NULL* *NULL* *NULL* *NULL* 0 0 0 0 0 {00000000-0000-0000-0000-000000000000} 0 13:11:04.916 [13164] <2> resourcesAvailable: DRIVE 3 *NULL* 0 *NULL* *NULL* 0 0 0 0 0 0 0 0 *NULL* *NULL* *NULL* *NULL* 0 0 0 1 0 0 13:11:04.916 [13164] <2> resourcesAvailable: STORAGE 1 BFB-VMWARE-OST 0 524288 0 6 0 0 lonbfbnbumedia.ipcmedia.com lonbfbnbumedia.ipcmedia.com *NULL* 13:11:04.916 [13164] <2> resourcesAvailable: DISKGROUP 0 20 hp-StoreOnceCatalyst 14 10.132.126.163 14 BFB-VMWARE-OST 13:11:04.916 [13164] <2> resourcesAvailable: DISKVOLUME 0 6 @aaaa8 14 BFB-VMWARE-OST 0 13:11:04.916 [13164] <2> resourcesAvailable: DISKMOUNTPOINT 0 14 BFB-VMWARE-OST 13:11:04.916 [13164] <2> EndpointSelectorFactory::get_selector: epsr2: got 7.x EPS(Endpoint_Selector.cpp:2007) 13:11:04.916 [13164] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:530) 13:11:04.916 [13164] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Evaluating 2 endpoints(Endpoint_Selector.cpp:631) 13:11:04.916 [13164] <2> ConnectList::search: host 10.132.112.22 found GOOD(ConnectList.cpp:89) 13:11:04.916 [13164] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 13:11:04.916 [13164] <2> EndpointSelector_R2::extractByAddressFamily: epsr2: address_binding: 10.132.112.22: ANY(Endpoint_Selector.cpp:1725) 13:11:04.916 [13164] <2> EndpointSelector_R2::cachedHostLookup: epsr2: Hostname 10.132.112.22 has 1 candidate addresses(Endpoint_Selector.cpp:1654) 13:11:04.916 [13164] <2> ConnectList::search: host 10.132.112.22 found GOOD(ConnectList.cpp:89) 13:11:04.916 [13164] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Reusing endpoint, target: 10.132.112.22, source: ANY(Endpoint_Selector.cpp:687) 13:11:04.916 [13164] <2> VxssContext::updateOnFailure: epsr2: updateOnFailure?=0 useVxss=2(Endpoint_Selector.cpp:243) 13:11:04.916 [13164] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure invocation->perform_call(...) to host 10.132.112.22 SUCCESS(Endpoint_Selector.cpp:1186) 13:11:04.916 [13164] <2> ConnectList::update: updated host 10.132.112.22 as GOOD, used 0 of 3 retries(ConnectList.cpp:175) 13:11:04.917 [13164] <2> packageInitialResourceRequestResult: totalNumberOfAllocations == 1 13:11:04.917 [13164] <2> packageInitialResourceRequestResult: m_allocationSeq.length=1 13:11:04.917 [13164] <2> packageInitialResourceRequestResult: found MediaAllocation : reqNum == 0 13:11:04.917 [13164] <2> packageInitialResourceRequestResult: matched MediaAllocation to an initialStuReq 13:11:04.917 [13164] <2> packageInitialResourceRequestResult: retVal = 0 13:11:04.917 [13164] <2> RequestInitialResources: retVal = 0 emmStatus = 0 13:11:04.917 [13164] <2> RequestInitialResources: returning 13:11:04.917 [13164] <2> populateBptmOpaqueStrings: packaging bptm string [MEDIADB 1 85461 *NULL* 0 *NULL* 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0], length 63 13:11:04.917 [13164] <2> populateBptmOpaqueStrings: packaging bptm string [VOLUME 1 *NULL* 0 *NULL* *NULL* *NULL* *NULL* 0 0 0 0 0 {00000000-0000-0000-0000-000000000000} 0], length 96 13:11:04.917 [13164] <2> populateBptmOpaqueStrings: packaging bptm string [DRIVE 3 *NULL* 0 *NULL* *NULL* 0 0 0 0 0 0 0 0 *NULL* *NULL* *NULL* *NULL* 0 0 0 1 0 0], length 86 13:11:04.917 [13164] <2> populateBptmOpaqueStrings: packaging bptm string [STORAGE 1 BFB-VMWARE-OST 0 524288 0 6 0 0 lonbfbnbumedia.ipcmedia.com lonbfbnbumedia.ipcmedia.com *NULL*], length 104 13:11:04.917 [13164] <2> populateBptmOpaqueStrings: packaging bptm string [DISKGROUP 0 20 hp-StoreOnceCatalyst 14 10.132.126.163 14 BFB-VMWARE-OST], length 71 13:11:04.917 [13164] <2> populateBptmOpaqueStrings: packaging bptm string [DISKVOLUME 0 6 @aaaa8 14 BFB-VMWARE-OST 0], length 41 13:11:04.917 [13164] <2> populateBptmOpaqueStrings: packaging bptm string [DISKMOUNTPOINT 0 14 BFB-VMWARE-OST], length 34 13:11:04.917 [13164] <2> populateBptmOpaqueStrings: retVal = 0 13:11:04.917 [13164] <2> parse_resource_strings: MEDIADB 1 85461 *NULL* 0 *NULL* 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 13:11:04.917 [13164] <2> parse_resource_strings: Parsed message type 15, version 1, 21 parameters 13:11:04.917 [13164] <2> parse_resource_strings: VOLUME 1 *NULL* 0 *NULL* *NULL* *NULL* *NULL* 0 0 0 0 0 {00000000-0000-0000-0000-000000000000} 0 13:11:04.917 [13164] <2> parse_resource_strings: Parsed message type 16, version 1, 14 parameters 13:11:04.917 [13164] <2> parse_resource_strings: DRIVE 3 *NULL* 0 *NULL* *NULL* 0 0 0 0 0 0 0 0 *NULL* *NULL* *NULL* *NULL* 0 0 0 1 0 0 13:11:04.917 [13164] <2> parse_resource_strings: Parsed message type 17, version 3, 23 parameters 13:11:04.917 [13164] <2> parse_resource_strings: STORAGE 1 BFB-VMWARE-OST 0 524288 0 6 0 0 lonbfbnbumedia.ipcmedia.com lonbfbnbumedia.ipcmedia.com *NULL* 13:11:04.917 [13164] <2> parse_resource_strings: Parsed message type 18, version 1, 11 parameters 13:11:04.917 [13164] <2> parse_resource_strings: DISKGROUP 0 20 hp-StoreOnceCatalyst 14 10.132.126.163 14 BFB-VMWARE-OST 13:11:04.917 [13164] <2> parse_resource_strings: Parsed message type 21, version 0, 7 parameters 13:11:04.917 [13164] <2> parse_resource_strings: DISKVOLUME 0 6 @aaaa8 14 BFB-VMWARE-OST 0 13:11:04.917 [13164] <2> parse_resource_strings: Parsed message type 22, version 0, 6 parameters 13:11:04.917 [13164] <2> parse_resource_strings: DISKMOUNTPOINT 0 14 BFB-VMWARE-OST 13:11:04.917 [13164] <2> parse_resource_strings: Parsed message type 23, version 0, 3 parameters 13:11:04.917 [13164] <2> nbjm_media_request: Job control returned to BPTM 13:11:04.917 [13164] <2> setup_twin_parameters: bptm.c.3957: maximum fragment size is 524288000 Kbytes 13:11:04.917 [13164] <2> dsmlibp_emmserver: dsmlib_common.cpp: 151 sev 4 EMM server set to bfbackup 13:11:04.917 [13164] <2> dsmlibp_masterserver: dsmlib_common.cpp: 174 sev 4 Master server set to bfbackup.ipcmedia.com 13:11:04.917 [13164] <2> dsmlibp_connect_dsm: dsmlib_common.cpp: 256 sev 4 Connecting to DiskServiceMgr on bfbackup 13:11:04.917 [13164] <2> dsmlibp_get_orb: dsmlib_common.cpp: 51 sev 4 Using dsmlib private orb 13:11:04.917 [13164] <2> Evictor::Evictor(): +++ ENTERING +++ : obj = 0xbcbf460 (../Evictor.cpp:198) 13:11:04.917 [13164] <2> Evictor::Evictor(): --- EXITING --- : obj = 0xbcbf460 (../Evictor.cpp:198) 13:11:04.917 [13164] <2> Orb::Orb: +++ ENTERING +++ : obj = 0xbcbf210 (../Orb.cpp:360) 13:11:04.918 [13164] <2> SetMaxFileNum: current max fileno: 1024, max fileno hard limit: 1024(SetMaxFileNum.cpp:34) 13:11:04.918 [13164] <2> SetMaxFileNum: successfully set max fileno: current=1024, max=1024(SetMaxFileNum.cpp:90) 13:11:04.918 [13164] <2> Orb::Orb: --- EXITING --- : obj = 0xbcbf210 (../Orb.cpp:360) 13:11:04.918 [13164] <2> Orb::init: +++ ENTERING +++ : obj = 0xbcbf210 (../Orb.cpp:501) 13:11:04.918 [13164] <2> Orb::init: checking if NBAC is enabled(Orb.cpp:518) 13:11:04.918 [13164] <2> checkService: found service: UTF16_BOM_Factory(Orb.cpp:494) 13:11:04.918 [13164] <2> checkService: found service: UTF8-Latin1_Factory(Orb.cpp:494) 13:11:04.918 [13164] <2> LogMaxFileNum: current max fileno: 1024, max fileno hard limit: 1024(SetMaxFileNum.cpp:113) 13:11:04.918 [13164] <2> Orb::getConfig: found service entry: name = veritas_pbx port = 1556 proto = tcp(Orb.cpp:412) 13:11:04.918 [13164] <2> Orb::getConfig: cluster_name and required_interface not defined, using ANY(Orb.cpp:464) 13:11:04.918 [13164] <2> Orb::init: Enabling ORBNativeCharCodeSet UTF-8(Orb.cpp:604) 13:11:04.918 [13164] <2> Orb::init: substituting orb name of: Default_CLIENT_Orb(Orb.cpp:638) 13:11:04.918 [13164] <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_CLIENT_Orb'"(Orb.cpp:657) 13:11:04.918 [13164] <2> Orb::init: added args has value of: (Orb.cpp:787) 13:11:04.918 [13164] <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:842) 13:11:04.918 [13164] <2> NBOrbInitializer::pre_init: +++ ENTERING +++ : obj = 0xbc1c2e0 (../NBOrbInitializer.cpp:45) 13:11:04.918 [13164] <2> NBOrbInitializer::pre_init: --- EXITING --- : obj = 0xbc1c2e0 (../NBOrbInitializer.cpp:45) 13:11:04.918 [13164] <2> NBOrbInitializer::pre_init: +++ ENTERING +++ : obj = 0xbc5de30 (../NBOrbInitializer.cpp:45) 13:11:04.918 [13164] <2> NBOrbInitializer::pre_init: --- EXITING --- : obj = 0xbc5de30 (../NBOrbInitializer.cpp:45) 13:11:04.918 [13164] <2> NBOrbInitializer::pre_init: +++ ENTERING +++ : obj = 0xbcd7480 (../NBOrbInitializer.cpp:45) 13:11:04.918 [13164] <2> NBOrbInitializer::pre_init: --- EXITING --- : obj = 0xbcd7480 (../NBOrbInitializer.cpp:45) 13:11:04.918 [13164] <2> NBOrbInitializer::post_init: +++ ENTERING +++ : obj = 0xbc1c2e0 (../NBOrbInitializer.cpp:53) 13:11:04.918 [13164] <2> NBOrbInitializer::post_init: --- EXITING --- : obj = 0xbc1c2e0 (../NBOrbInitializer.cpp:53) 13:11:04.918 [13164] <2> NBOrbInitializer::post_init: +++ ENTERING +++ : obj = 0xbc5de30 (../NBOrbInitializer.cpp:53) 13:11:04.918 [13164] <2> NBOrbInitializer::post_init: --- EXITING --- : obj = 0xbc5de30 (../NBOrbInitializer.cpp:53) 13:11:04.918 [13164] <2> NBOrbInitializer::post_init: +++ ENTERING +++ : obj = 0xbcd7480 (../NBOrbInitializer.cpp:53) 13:11:04.918 [13164] <2> NBOrbInitializer::post_init: Adding interceptors(NBOrbInitializer.cpp:74) 13:11:04.918 [13164] <2> NBIORInterceptor::NBIORInterceptor: Interceptor Name: _Default_CLIENT_Orb(NBIORInterceptor.cpp:47) 13:11:04.918 [13164] <2> NBOrbInitializer::post_init: --- EXITING --- : obj = 0xbcd7480 (../NBOrbInitializer.cpp:53) 13:11:04.918 [13164] <2> Orb::init: caching EndpointSelectorFactory(Orb.cpp:857) 13:11:04.918 [13164] <2> Orb::setOrbTimeoutPolicy: setting ORB connection timeout policy: tv = 300(Orb.cpp:1521) 13:11:04.918 [13164] <2> Orb::activate: +++ ENTERING +++ : obj = 0xbcbf210 (../Orb.cpp:1248) 13:11:04.918 [13164] <2> Orb::activate: Client Orb called activate; Ignored(Orb.cpp:1253) 13:11:04.918 [13164] <2> Orb::activate: --- EXITING --- : obj = 0xbcbf210 (../Orb.cpp:1248) 13:11:04.918 [13164] <2> Orb::init: --- EXITING --- : obj = 0xbcbf210 (../Orb.cpp:501) 13:11:04.918 [13164] <2> dsmlibp_connect_dsm: dsmlib_common.cpp: 264 sev 4 Connecting to NBDSMFSM using orb 0xbcbf210 13:11:04.918 [13164] <2> Orb::connectToObject: Connection attempt #1 mW:5 rW:0(Orb.cpp:1184) 13:11:04.918 [13164] <2> Orb::createInsecureObjectRef: +++ ENTERING +++ : obj = 0xbcbf210 (../Orb.cpp:1449) 13:11:04.918 [13164] <2> Orb::createInsecureObjectRef: corbaloc being used is: corbaloc:pbxiop:bfbackup:1556:NBDSMFSM/DiskService.DiskServiceMgr(Orb.cpp:1457) 13:11:04.918 [13164] <2> Orb::createInsecureObjectRef: --- EXITING --- : obj = 0xbcbf210 (../Orb.cpp:1449) 13:11:04.918 [13164] <2> Orb::connectToObjectOnce: attempt to create insecure connection(Orb.cpp:1051) 13:11:04.918 [13164] <2> EndpointSelectorFactory::get_selector: epsr2: got 7.x EPS(Endpoint_Selector.cpp:2007) 13:11:04.918 [13164] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:530) 13:11:04.918 [13164] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Evaluating 1 endpoints(Endpoint_Selector.cpp:631) 13:11:04.918 [13164] <2> ConnectList::search: host bfbackup not found in cache(ConnectList.cpp:116) 13:11:04.918 [13164] <2> VxssContext::useVxss: epsr2: Setting up useVxss=2(Endpoint_Selector.cpp:157) 13:11:04.918 [13164] <2> VxssContext::begin: epsr2: Setup context, passes. vxss=2 passes=1(Endpoint_Selector.cpp:187) 13:11:04.918 [13164] <2> VxssContext::end: epsr2: all done? passes=1 curridx=0(Endpoint_Selector.cpp:193) 13:11:04.919 [13164] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: pass: 0 secure_mode:2/0(Endpoint_Selector.cpp:778) 13:11:04.919 [13164] <2> ConnectList::search: host bfbackup not found in cache(ConnectList.cpp:116) 13:11:04.919 [13164] <2> EndpointSelector_R2::evaluateEndpoint: epsr2: PBXIOP endpoint host: bfbackup(Endpoint_Selector.cpp:1471) 13:11:04.919 [13164] <2> ConnectList::search: host 10.132.112.22 found GOOD(ConnectList.cpp:89) 13:11:04.919 [13164] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 13:11:04.919 [13164] <2> EndpointSelector_R2::extractByAddressFamily: epsr2: address_binding: 10.132.112.22: ANY(Endpoint_Selector.cpp:1725) 13:11:04.919 [13164] <2> EndpointSelector_R2::cachedHostLookup: epsr2: Hostname bfbackup has 1 candidate addresses(Endpoint_Selector.cpp:1654) 13:11:04.919 [13164] <2> EndpointSelector_R2::evaluateEndpoint: epsr2: Endpoint host bfbackup is not checked against server list(Endpoint_Selector.cpp:1590) 13:11:04.919 [13164] <2> EndpointSelector_R2::evaluateEndpoint: epsr2: Found 1 usable IP addresses for host bfbackup:[10.132.112.22] (Endpoint_Selector.cpp:1599) 13:11:04.919 [13164] <2> ConnectList::search: host 10.132.112.22 found GOOD(ConnectList.cpp:89) 13:11:04.919 [13164] <2> ExternalResolver::set: Resolved host_name 10.132.112.22[10.132.112.22](ExternalResolver.cpp:56) 13:11:04.919 [13164] <2> EndpointSelector_R2::performCallUsingEndpointAndSourceBinding: epsr2: Host resolved to GOOD address, target: 10.132.112.22, source: ANY(Endpoint_Selector.cpp:940) 13:11:04.919 [13164] <2> VxssContext::updateOnFailure: epsr2: updateOnFailure?=1 useVxss=2(Endpoint_Selector.cpp:243) 13:11:04.919 [13164] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure invocation->perform_call(...) to host 10.132.112.22 SUCCESS(Endpoint_Selector.cpp:1186) 13:11:04.919 [13164] <2> ConnectList::update: updated host 10.132.112.22 as GOOD, used 0 of 3 retries(ConnectList.cpp:175) 13:11:04.919 [13164] <2> VxssContext::end: epsr2: all done? passes=1 curridx=1(Endpoint_Selector.cpp:193) 13:11:04.921 [13164] <2> EndpointSelector_R2::forward: epsr2: Adding 1 forward profiles(Endpoint_Selector.cpp:555) 13:11:04.921 [13164] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:530) 13:11:04.921 [13164] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Evaluating 2 endpoints(Endpoint_Selector.cpp:631) 13:11:04.921 [13164] <2> ConnectList::search: host bfbackup.ipcmedia.com not found in cache(ConnectList.cpp:116) 13:11:04.921 [13164] <2> ExternalResolver::set: Resolved host_name 10.132.112.22[10.132.112.22](ExternalResolver.cpp:56) 13:11:04.921 [13164] <2> EndpointSelector_R2::evaluateNatBindings: epsr2: Target host does not advertise the address we originally connected to. Replacing bfbackup.ipcmedia.com with 10.132.112.22 in connect list.(Endpoint_Selector.cpp:1915) 13:11:04.921 [13164] <2> ConnectList::search: host 10.132.112.22 found GOOD(ConnectList.cpp:89) 13:11:04.921 [13164] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Reusing endpoint, target: 10.132.112.22, source: ANY(Endpoint_Selector.cpp:687) 13:11:04.921 [13164] <2> VxssContext::updateOnFailure: epsr2: updateOnFailure?=0 useVxss=2(Endpoint_Selector.cpp:243) 13:11:04.921 [13164] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure invocation->perform_call(...) to host 10.132.112.22 SUCCESS(Endpoint_Selector.cpp:1186) 13:11:04.921 [13164] <2> ConnectList::update: updated host 10.132.112.22 as GOOD, used 0 of 3 retries(ConnectList.cpp:175) 13:11:04.921 [13164] <2> Orb::connectToObjectOnce: connection created(Orb.cpp:1069) 13:11:04.921 [13164] <2> FrameworkRegistrar::init: +++ ENTERING +++ : obj = 0x7fff067db820 (../FrameworkRegistrar.cpp:131) 13:11:04.921 [13164] <2> FrameworkRegistrar::init: --- EXITING --- : obj = 0x7fff067db820 (../FrameworkRegistrar.cpp:131) 13:11:04.921 [13164] <2> dsmlibp_register_factories: dsmlib_common.cpp: 330 sev 4 Registerd Framework OBV factories with orb 0xbcbf210 13:11:04.921 [13164] <2> DiskServiceRegistrar::init: +++ ENTERING +++ : obj = 0x7fff067db7d0 (../DiskServiceRegistrar.cpp:144) 13:11:04.922 [13164] <2> DiskServiceRegistrar::init: --- EXITING --- : obj = 0x7fff067db7d0 (../DiskServiceRegistrar.cpp:144) 13:11:04.922 [13164] <2> dsmlibp_register_factories: dsmlib_common.cpp: 338 sev 4 Registerd DiskServiceMgr OBV factories with orb 0xbcbf210 13:11:04.922 [13164] <2> EndpointSelectorFactory::get_selector: epsr2: got 7.x EPS(Endpoint_Selector.cpp:2007) 13:11:04.922 [13164] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:530) 13:11:04.922 [13164] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Evaluating 2 endpoints(Endpoint_Selector.cpp:631) 13:11:04.922 [13164] <2> ConnectList::search: host 10.132.112.22 found GOOD(ConnectList.cpp:89) 13:11:04.922 [13164] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 13:11:04.922 [13164] <2> EndpointSelector_R2::extractByAddressFamily: epsr2: address_binding: 10.132.112.22: ANY(Endpoint_Selector.cpp:1725) 13:11:04.922 [13164] <2> EndpointSelector_R2::cachedHostLookup: epsr2: Hostname 10.132.112.22 has 1 candidate addresses(Endpoint_Selector.cpp:1654) 13:11:04.922 [13164] <2> ConnectList::search: host 10.132.112.22 found GOOD(ConnectList.cpp:89) 13:11:04.922 [13164] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Reusing endpoint, target: 10.132.112.22, source: ANY(Endpoint_Selector.cpp:687) 13:11:04.922 [13164] <2> VxssContext::updateOnFailure: epsr2: updateOnFailure?=0 useVxss=2(Endpoint_Selector.cpp:243) 13:11:04.922 [13164] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure invocation->perform_call(...) to host 10.132.112.22 SUCCESS(Endpoint_Selector.cpp:1186) 13:11:04.922 [13164] <2> ConnectList::update: updated host 10.132.112.22 as GOOD, used 0 of 3 retries(ConnectList.cpp:175) 13:11:04.922 [13164] <2> dsm_get_diskvolumeinfo_by_mediaid: dsmlib_diskgroup.cpp: 1682 sev 2 diskvolume media ID = @aaaa8 13:11:04.922 [13164] <2> dsm_get_diskvolumeinfo_by_mediaid: dsmlib_diskgroup.cpp: 1685 sev 2 Calling dsm->getDiskVolumeInfoByMediaId() 13:11:04.922 [13164] <2> EndpointSelectorFactory::get_selector: epsr2: got 7.x EPS(Endpoint_Selector.cpp:2007) 13:11:04.922 [13164] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:530) 13:11:04.922 [13164] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Evaluating 2 endpoints(Endpoint_Selector.cpp:631) 13:11:04.922 [13164] <2> ConnectList::search: host 10.132.112.22 found GOOD(ConnectList.cpp:89) 13:11:04.922 [13164] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 13:11:04.922 [13164] <2> EndpointSelector_R2::extractByAddressFamily: epsr2: address_binding: 10.132.112.22: ANY(Endpoint_Selector.cpp:1725) 13:11:04.922 [13164] <2> EndpointSelector_R2::cachedHostLookup: epsr2: Hostname 10.132.112.22 has 1 candidate addresses(Endpoint_Selector.cpp:1654) 13:11:04.922 [13164] <2> ConnectList::search: host 10.132.112.22 found GOOD(ConnectList.cpp:89) 13:11:04.922 [13164] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Reusing endpoint, target: 10.132.112.22, source: ANY(Endpoint_Selector.cpp:687) 13:11:04.922 [13164] <2> VxssContext::updateOnFailure: epsr2: updateOnFailure?=0 useVxss=2(Endpoint_Selector.cpp:243) 13:11:04.922 [13164] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure invocation->perform_call(...) to host 10.132.112.22 SUCCESS(Endpoint_Selector.cpp:1186) 13:11:04.922 [13164] <2> ConnectList::update: updated host 10.132.112.22 as GOOD, used 0 of 3 retries(ConnectList.cpp:175) 13:11:04.933 [13164] <2> dsml_extract_diskvolumeinfo: dsmlib_popext.cpp: 464 sev 2 diskvolume.name = BFB-VMWARE-OST 13:11:04.933 [13164] <2> dsml_extract_diskvolumeinfo: dsmlib_popext.cpp: 472 sev 2 diskvolume.id = BFB-VMWARE-OST 13:11:04.933 [13164] <2> dsml_extract_diskvolumeinfo: dsmlib_popext.cpp: 478 sev 2 diskvolumeinfo.total_capacity = 120951273624699 13:11:04.933 [13164] <2> dsml_extract_diskvolumeinfo: dsmlib_popext.cpp: 482 sev 2 diskvolumeinfo.free_space = 113398731070412 13:11:04.933 [13164] <2> dsml_extract_diskvolumeinfo: dsmlib_popext.cpp: 574 sev 2 property.name=DSM::master, property.value=bfbackup 13:11:04.933 [13164] <2> dsml_extract_diskvolumeinfo: dsmlib_popext.cpp: 574 sev 2 property.name=DSM::flags, property.value=14 13:11:04.933 [13164] <2> dsml_extract_diskvolumeinfo: dsmlib_popext.cpp: 549 sev 2 diskvolumeinfo.nbu_state = conv_fn(1) 13:11:04.933 [13164] <2> dsml_extract_diskvolumeinfo: dsmlib_popext.cpp: 574 sev 2 property.name=DSM::fileSystemType, property.value=0 13:11:04.933 [13164] <2> dsml_extract_diskvolumeinfo: dsmlib_popext.cpp: 574 sev 2 property.name=DSM::fileSystemOrdinal, property.value=0 13:11:04.933 [13164] <2> dsml_extract_diskvolumeinfo: dsmlib_popext.cpp: 564 sev 2 diskvolumeinfo.max_write_mounts = 1 13:11:04.933 [13164] <2> dsml_extract_diskvolumeinfo: dsmlib_popext.cpp: 569 sev 2 diskvolumeinfo.num_write_mounts = 1 13:11:04.933 [13164] <2> dsml_extract_diskvolumeinfo: dsmlib_popext.cpp: 574 sev 2 property.name=DSM::maxReferenceWriters, property.value=2147483647 13:11:04.933 [13164] <2> dsml_extract_diskvolumeinfo: dsmlib_popext.cpp: 554 sev 2 diskvolumeinfo.max_read_mounts = 0 13:11:04.933 [13164] <2> dsml_extract_diskvolumeinfo: dsmlib_popext.cpp: 559 sev 2 diskvolumeinfo.num_read_mounts = 0 13:11:04.933 [13164] <2> dsml_extract_diskvolumeinfo: dsmlib_popext.cpp: 574 sev 2 property.name=DSM::maxReferenceReaders, property.value=0 13:11:04.933 [13164] <2> dsml_extract_diskvolumeinfo: dsmlib_popext.cpp: 574 sev 2 property.name=DSM::freeSpace, property.value=113398731070412 13:11:04.934 [13164] <2> dsml_extract_diskvolumeinfo: dsmlib_popext.cpp: 574 sev 2 property.name=DSM::freePhysicalSpace, property.value=113398731070412 13:11:04.934 [13164] <2> dsml_extract_diskvolumeinfo: dsmlib_popext.cpp: 511 sev 2 diskvolumeinfo.potential_free_space = 0 13:11:04.934 [13164] <2> dsml_extract_diskvolumeinfo: dsmlib_popext.cpp: 516 sev 2 diskvolumeinfo.committed_space = 2419025471488 13:11:04.934 [13164] <2> dsml_extract_diskvolumeinfo: dsmlib_popext.cpp: 521 sev 2 diskvolumeinfo.precommitted_space = 2419025471488 13:11:04.934 [13164] <2> dsml_extract_diskvolumeinfo: dsmlib_popext.cpp: 531 sev 2 diskvolumeinfo.system_tag = Unknown 13:11:04.934 [13164] <2> dsml_extract_diskvolumeinfo: dsmlib_popext.cpp: 526 sev 2 diskvolumeinfo.disk_media_id = @aaaa8 13:11:04.934 [13164] <2> dsml_extract_diskvolumeinfo: dsmlib_popext.cpp: 574 sev 2 property.name=DSM::activeReadStreams, property.value=0 13:11:04.934 [13164] <2> dsml_extract_diskvolumeinfo: dsmlib_popext.cpp: 574 sev 2 property.name=DSM::activeWriteStreams, property.value=1 13:11:04.934 [13164] <2> dsml_extract_diskvolumeinfo: dsmlib_popext.cpp: 574 sev 2 property.name=DSM::createdTime, property.value=1384266049 13:11:04.934 [13164] <2> dsml_extract_diskvolumeinfo: dsmlib_popext.cpp: 574 sev 2 property.name=DSM::lastModifiedTime, property.value=1389618645 13:11:04.934 [13164] <2> dsml_extract_diskvolumeinfo: dsmlib_popext.cpp: 574 sev 2 property.name=DSM::totalPhysicalCapacity, property.value=120951273624699 13:11:04.934 [13164] <2> dsml_extract_diskvolumeinfo: dsmlib_popext.cpp: 574 sev 2 property.name=DSM::totalCapacity, property.value=120951273624699 13:11:04.934 [13164] <2> dsml_extract_diskvolumeinfo: dsmlib_popext.cpp: 574 sev 2 property.name=DSMi::ReplicationSourceAndTargetSpecCount, property.value=0 13:11:04.934 [13164] <2> dsml_extract_diskvolumeinfo: dsmlib_popext.cpp: 574 sev 2 property.name=DSM::majorEventSyncToken, property.value=1389618645 13:11:04.934 [13164] <2> dsml_extract_diskvolumeinfo: dsmlib_popext.cpp: 574 sev 2 property.name=DSM::minorEventSyncToken, property.value=1012 13:11:04.934 [13164] <2> dsml_extract_diskvolumeinfo: dsmlib_popext.cpp: 574 sev 2 property.name=DSM::diskGroupName, property.value=BFB-VMWARE-OST 13:11:04.934 [13164] <2> dsm_get_diskvolumeinfo_by_mediaid: dsmlib_diskgroup.cpp: 1708 sev 2 diskvolumeinfo.diskgroup.name = BFB-VMWARE-OST 13:11:04.934 [13164] <2> dsm_get_diskvolumeinfo_by_mediaid: dsmlib_diskgroup.cpp: 1720 sev 2 diskvolumeinfo.diskgroup.master_server = bfbackup 13:11:04.934 [13164] <2> dsm_get_diskvolumeinfo_by_mediaid: dsmlib_diskgroup.cpp: 1731 sev 2 diskvolumeinfo.diskgroup.server_type = hp-StoreOnceCatalyst 13:11:04.934 [13164] <2> validate_parameters: tmcommon.c.5419: maximum fragment size is 524288000 Kbytes 13:11:04.934 [13164] <2> io_init: bpbrm_shm_id = 4915206, buffer address = 0x0x2b4f5e657000 13:11:04.934 [13164] <2> io_init: found index: 0 13:11:04.934 [13164] <2> io_init: using 262144 data buffer size 13:11:04.934 [13164] <2> set_job_details: Tfile (233793): LOG 1389618664 4 bptm 13164 using 262144 data buffer size 13:11:04.934 [13164] <2> send_job_file: job ID 233793, ftype = 3 msg len = 58, msg = LOG 1389618664 4 bptm 13164 using 262144 data buffer size 13:11:04.934 [13164] <2> io_init: CINDEX 0, sched Kbytes for monitoring = 400000 13:11:04.934 [13164] <2> io_set_recvbuf: receive network buffer is 87380 bytes 13:11:04.934 [13164] <2> io_init: using 30 data buffers 13:11:04.934 [13164] <2> set_job_details: Tfile (233793): LOG 1389618664 4 bptm 13164 using 30 data buffers 13:11:04.934 [13164] <2> send_job_file: job ID 233793, ftype = 3 msg len = 50, msg = LOG 1389618664 4 bptm 13164 using 30 data buffers 13:11:04.934 [13164] <2> io_init: child delay = 10, parent delay = 15 (milliseconds) 13:11:04.934 [13164] <2> create_shared_memory: shm_size = 7865048, buffer address = 0x0x2b4f5ea7f000, buf control = 0x0x2b4f5f1ff000, ready ptr = 0x0x2b4f5f1ff2d0 13:11:04.934 [13164] <2> 233793:bptm:13164:lonbfbnbumedia.ipcmedia.com: libsts load_plugins() 14/01/13 13:11:04: opening plugin directory /usr/openv/lib 13:11:04.935 [13164] <2> 233793:bptm:13164:lonbfbnbumedia.ipcmedia.com: libsts openp() 14/01/13 13:11:04: opening module /usr/openv/lib/libstspishmem.so 13:11:04.935 [13164] <2> 233793:bptm:13164:lonbfbnbumedia.ipcmedia.com: libsts openp() 14/01/13 13:11:04: opening module /usr/openv/lib/libstspinearstore.so 13:11:04.936 [13164] <2> 233793:bptm:13164:lonbfbnbumedia.ipcmedia.com: libsts openp() 14/01/13 13:11:04: opening module /usr/openv/lib/libstspinbostpxy.so 13:11:04.963 [13164] <2> 233793:bptm:13164:lonbfbnbumedia.ipcmedia.com: libsts openp() 14/01/13 13:11:04: opening module /usr/openv/lib/libstspicp.so 13:11:04.964 [13164] <2> 233793:bptm:13164:lonbfbnbumedia.ipcmedia.com: libsts openp() 14/01/13 13:11:04: opening module /usr/openv/lib/libstspibasicdisk.so 13:11:04.966 [13164] <2> 233793:bptm:13164:lonbfbnbumedia.ipcmedia.com: libsts load_plugins() 14/01/13 13:11:04: opening plugin directory /usr/openv/lib/ost-plugins 13:11:04.966 [13164] <2> 233793:bptm:13164:lonbfbnbumedia.ipcmedia.com: libsts openp() 14/01/13 13:11:04: opening module /usr/openv/lib/ost-plugins/libstspigateway.so 13:11:04.988 [13164] <2> 233793:bptm:13164:lonbfbnbumedia.ipcmedia.com: libsts openp() 14/01/13 13:11:04: opening module /usr/openv/lib/ost-plugins/libstspinirvanix.so 13:11:04.992 [13164] <2> 233793:bptm:13164:lonbfbnbumedia.ipcmedia.com: libsts openp() 14/01/13 13:11:04: opening module /usr/openv/lib/ost-plugins/libstspisoapwrapper.so 13:11:05.001 [13164] <2> 233793:bptm:13164:lonbfbnbumedia.ipcmedia.com: libsts openp() 14/01/13 13:11:05: opening module /usr/openv/lib/ost-plugins/libstspithrottling.so 13:11:05.002 [13164] <2> 233793:bptm:13164:lonbfbnbumedia.ipcmedia.com: libsts openp() 14/01/13 13:11:05: opening module /usr/openv/lib/ost-plugins/libstspiencrypt.so 13:11:05.007 [13164] <2> Evictor::Evictor(): +++ ENTERING +++ : obj = 0x2b4f61eae670 (../Evictor.cpp:198) 13:11:05.007 [13164] <2> Evictor::Evictor(): --- EXITING --- : obj = 0x2b4f61eae670 (../Evictor.cpp:198) 13:11:05.007 [13164] <2> Orb::Orb: +++ ENTERING +++ : obj = 0x2b4f61eae420 (../Orb.cpp:360) 13:11:05.007 [13164] <2> SetMaxFileNum: current max fileno: 1024, max fileno hard limit: 1024(SetMaxFileNum.cpp:34) 13:11:05.007 [13164] <2> SetMaxFileNum: successfully set max fileno: current=1024, max=1024(SetMaxFileNum.cpp:90) 13:11:05.007 [13164] <2> Orb::Orb: --- EXITING --- : obj = 0x2b4f61eae420 (../Orb.cpp:360) 13:11:05.007 [13164] <2> 233793:bptm:13164:lonbfbnbumedia.ipcmedia.com: libsts openp() 14/01/13 13:11:05: opening module /usr/openv/lib/ost-plugins/libstspiamazon.so 13:11:05.010 [13164] <2> 233793:bptm:13164:lonbfbnbumedia.ipcmedia.com: libsts openp() 14/01/13 13:11:05: opening module /usr/openv/lib/ost-plugins/libstspipd.so 13:11:05.026 [13164] <2> 233793:bptm:13164:lonbfbnbumedia.ipcmedia.com: libsts openp() 14/01/13 13:11:05: opening module /usr/openv/lib/ost-plugins/libstspihpq20.so 13:11:05.031 [13164] <2> 233793:bptm:13164:lonbfbnbumedia.ipcmedia.com: libsts openp() 14/01/13 13:11:05: opening module /usr/openv/lib/ost-plugins/libstspiatt.so 13:11:05.034 [13164] <2> 233793:bptm:13164:lonbfbnbumedia.ipcmedia.com: libsts openp() 14/01/13 13:11:05: opening module /usr/openv/lib/ost-plugins/libstspirackspace.so 13:11:05.037 [13164] <2> 233793:bptm:13164:lonbfbnbumedia.ipcmedia.com: libsts openp() 14/01/13 13:11:05: opening module /usr/openv/lib/ost-plugins/libstspimetering.so 13:11:05.039 [13164] <2> get_plugin_version: libstspihpq20.so (hp-StoreOnceCatalyst==hp-StoreOnceCatalyst) build=10.0 operating=11 vendor 13:11:05.039 [13164] <2> bp_sts_open_server: plugin version = 11 13:11:05.039 [13164] <4> 233793:bptm:13164:lonbfbnbumedia.ipcmedia.com: libsts setupp() 14/01/13 13:11:05: hp-StoreOnceCatalyst:10.132.126.163 claimed by /usr/openv/lib/ost-plugins/libstspihpq20.so 13:11:05.039 [13164] <8> 233793:bptm:13164:lonbfbnbumedia.ipcmedia.com: [------ENTRY------] stspi_get_server_prop_byname 13:11:05.039 [13164] <8> 233793:bptm:13164:lonbfbnbumedia.ipcmedia.com: [------EXIT------] stspi_get_server_prop_byname - 0 13:11:05.039 [13164] <2> emmlib_GetHost: (-) MachineName Machine type <1> 13:11:05.039 [13164] <2> EndpointSelectorFactory::get_selector: epsr2: got 7.x EPS(Endpoint_Selector.cpp:2007) 13:11:05.039 [13164] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:530) 13:11:05.039 [13164] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Evaluating 2 endpoints(Endpoint_Selector.cpp:631) 13:11:05.039 [13164] <2> ConnectList::search: host 10.132.112.22 found GOOD(ConnectList.cpp:89) 13:11:05.039 [13164] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 13:11:05.039 [13164] <2> EndpointSelector_R2::extractByAddressFamily: epsr2: address_binding: 10.132.112.22: ANY(Endpoint_Selector.cpp:1725) 13:11:05.039 [13164] <2> EndpointSelector_R2::cachedHostLookup: epsr2: Hostname 10.132.112.22 has 1 candidate addresses(Endpoint_Selector.cpp:1654) 13:11:05.039 [13164] <2> ConnectList::search: host 10.132.112.22 found GOOD(ConnectList.cpp:89) 13:11:05.039 [13164] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Reusing endpoint, target: 10.132.112.22, source: ANY(Endpoint_Selector.cpp:687) 13:11:05.039 [13164] <2> VxssContext::updateOnFailure: epsr2: updateOnFailure?=0 useVxss=2(Endpoint_Selector.cpp:243) 13:11:05.039 [13164] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure invocation->perform_call(...) to host 10.132.112.22 SUCCESS(Endpoint_Selector.cpp:1186) 13:11:05.039 [13164] <2> ConnectList::update: updated host 10.132.112.22 as GOOD, used 0 of 3 retries(ConnectList.cpp:175) 13:11:05.040 [13164] <4> emmlib_GetHost: (0) Reading Machine record < lonbfbnbumedia.ipcmedia.com > from EMM database 13:11:05.040 [13164] <2> EndpointSelectorFactory::get_selector: epsr2: got 7.x EPS(Endpoint_Selector.cpp:2007) 13:11:05.040 [13164] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:530) 13:11:05.040 [13164] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Evaluating 2 endpoints(Endpoint_Selector.cpp:631) 13:11:05.040 [13164] <2> ConnectList::search: host bfbackup.ipcmedia.com not found in cache(ConnectList.cpp:116) 13:11:05.040 [13164] <2> ExternalResolver::set: Resolved host_name 10.132.112.22[10.132.112.22](ExternalResolver.cpp:56) 13:11:05.040 [13164] <2> EndpointSelector_R2::evaluateNatBindings: epsr2: Target host does not advertise the address we originally connected to. Replacing bfbackup.ipcmedia.com with 10.132.112.22 in connect list.(Endpoint_Selector.cpp:1915) 13:11:05.040 [13164] <2> ConnectList::search: host 10.132.112.22 found GOOD(ConnectList.cpp:89) 13:11:05.040 [13164] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Reusing endpoint, target: 10.132.112.22, source: ANY(Endpoint_Selector.cpp:687) 13:11:05.040 [13164] <2> VxssContext::updateOnFailure: epsr2: updateOnFailure?=0 useVxss=2(Endpoint_Selector.cpp:243) 13:11:05.041 [13164] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure invocation->perform_call(...) to host 10.132.112.22 SUCCESS(Endpoint_Selector.cpp:1186) 13:11:05.041 [13164] <2> ConnectList::update: updated host 10.132.112.22 as GOOD, used 0 of 3 retries(ConnectList.cpp:175) 13:11:05.046 [13164] <2> emmlib_GetHost: (0) MachineName = < lonbfbnbumedia.ipcmedia.com > FQName = < lonbfbnbumedia.ipcmedia.com > MachineGuid = < 591332156 15531 4579 80009FC76C783023 > MasterServerName = < bfbackup > ClusterName = < > ActiveNodeName = < > MachineNbuType = < 1 > MachineState = < 12 > MachineFlags = < 119 > MachineDescription = < > OperatingSystem = < 16 > ScanAbility = < 5 > NetBackupVersion = < 750600 > LocalDriveSeed = < > GlobalDriveSeed = < > HeartbeatFrequency = < 300 > 13:11:05.046 [13164] <2> emmlib_GetHost: (0) QueryMachine successful, emmError = 0, nbError = 0 13:11:05.046 [13164] <2> get_physical_machine_name: lonbfbnbumedia.ipcmedia.com is a Media server. Setting physical machine name to lonbfbnbumedia.ipcmedia.com 13:11:05.046 [13164] <2> EndpointSelectorFactory::get_selector: epsr2: got 7.x EPS(Endpoint_Selector.cpp:2007) 13:11:05.046 [13164] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:530) 13:11:05.046 [13164] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Evaluating 2 endpoints(Endpoint_Selector.cpp:631) 13:11:05.046 [13164] <2> ConnectList::search: host 10.132.112.22 found GOOD(ConnectList.cpp:89) 13:11:05.046 [13164] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 13:11:05.046 [13164] <2> EndpointSelector_R2::extractByAddressFamily: epsr2: address_binding: 10.132.112.22: ANY(Endpoint_Selector.cpp:1725) 13:11:05.046 [13164] <2> EndpointSelector_R2::cachedHostLookup: epsr2: Hostname 10.132.112.22 has 1 candidate addresses(Endpoint_Selector.cpp:1654) 13:11:05.046 [13164] <2> ConnectList::search: host 10.132.112.22 found GOOD(ConnectList.cpp:89) 13:11:05.046 [13164] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Reusing endpoint, target: 10.132.112.22, source: ANY(Endpoint_Selector.cpp:687) 13:11:05.046 [13164] <2> VxssContext::updateOnFailure: epsr2: updateOnFailure?=0 useVxss=2(Endpoint_Selector.cpp:243) 13:11:05.047 [13164] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure invocation->perform_call(...) to host 10.132.112.22 SUCCESS(Endpoint_Selector.cpp:1186) 13:11:05.047 [13164] <2> ConnectList::update: updated host 10.132.112.22 as GOOD, used 0 of 3 retries(ConnectList.cpp:175) 13:11:05.047 [13164] <2> emmlib_NdmpUserIdQuery: (0) Query filer < 10.132.126.163 > credentials of type < 0 > for the media server < lonbfbnbumedia.ipcmedia.com > 13:11:05.047 [13164] <2> EndpointSelectorFactory::get_selector: epsr2: got 7.x EPS(Endpoint_Selector.cpp:2007) 13:11:05.047 [13164] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:530) 13:11:05.047 [13164] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Evaluating 2 endpoints(Endpoint_Selector.cpp:631) 13:11:05.047 [13164] <2> ConnectList::search: host bfbackup.ipcmedia.com not found in cache(ConnectList.cpp:116) 13:11:05.047 [13164] <2> ExternalResolver::set: Resolved host_name 10.132.112.22[10.132.112.22](ExternalResolver.cpp:56) 13:11:05.047 [13164] <2> EndpointSelector_R2::evaluateNatBindings: epsr2: Target host does not advertise the address we originally connected to. Replacing bfbackup.ipcmedia.com with 10.132.112.22 in connect list.(Endpoint_Selector.cpp:1915) 13:11:05.047 [13164] <2> ConnectList::search: host 10.132.112.22 found GOOD(ConnectList.cpp:89) 13:11:05.047 [13164] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Reusing endpoint, target: 10.132.112.22, source: ANY(Endpoint_Selector.cpp:687) 13:11:05.047 [13164] <2> VxssContext::updateOnFailure: epsr2: updateOnFailure?=0 useVxss=2(Endpoint_Selector.cpp:243) 13:11:05.047 [13164] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure invocation->perform_call(...) to host 10.132.112.22 SUCCESS(Endpoint_Selector.cpp:1186) 13:11:05.047 [13164] <2> ConnectList::update: updated host 10.132.112.22 as GOOD, used 0 of 3 retries(ConnectList.cpp:175) 13:11:05.060 [13164] <2> emmlib_NdmpUserIdQuery: (0) queryNdmpCredentials successful, emmError = 0, nbError = 0 13:11:05.060 [13164] <4> 233793:bptm:13164:lonbfbnbumedia.ipcmedia.com: libsts setupp() 14/01/13 13:11:05: hp-StoreOnceCatalyst:10.132.126.163 claimed by /usr/openv/lib/ost-plugins/libstspihpq20.so 13:11:05.060 [13164] <8> 233793:bptm:13164:lonbfbnbumedia.ipcmedia.com: [------ENTRY------] stspi_open_server 13:11:05.068 [13164] <8> 233793:bptm:13164:lonbfbnbumedia.ipcmedia.com: [------EXIT------] stspi_open_server - 0 13:11:05.068 [13164] <2> bptm: STS attach host is 10.132.126.163 13:11:05.068 [13164] <2> ConnectionCache::connectAndCache: Acquiring new connection for host bfbackup.ipcmedia.com, query type 21 13:11:05.068 [13164] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 13:11:05.068 [13164] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 13:11:05.068 [13164] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 13:11:05.068 [13164] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 13:11:05.068 [13164] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 13:11:05.068 [13164] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 13:11:05.068 [13164] <2> async_connect: [vnet_connect.c:1477] connect in progress 1 0x1 13:11:05.069 [13164] <2> vnet_pbxConnect: pbxConnectEx Succeeded 13:11:05.069 [13164] <2> do_pbx_service: [vnet_connect.c:2116] via PBX bpdbm CONNECT FROM 10.132.113.128.42319 TO 10.132.112.22.1556 fd = 22 13:11:05.069 [13164] <2> async_connect: [vnet_connect.c:1644] connect async CONNECT FROM 10.132.113.128.42319 TO 10.132.112.22.1556 fd = 22 13:11:05.069 [13164] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO bfbackup.ipcmedia.com 10.132.112.22 bpdbm VIA pbx 13:11:05.069 [13164] <2> logconnections: BPDBM CONNECT FROM 10.132.113.128.42319 TO 10.132.112.22.1556 fd = 22 13:11:05.069 [13164] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2 13:11:05.085 [13164] <2> db_end: Need to collect reply 13:11:05.085 [13164] <2> ConnectionCache::connectAndCache: Acquiring new connection for host bfbackup.ipcmedia.com, query type 1 13:11:05.085 [13164] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 13:11:05.085 [13164] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 13:11:05.085 [13164] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 13:11:05.085 [13164] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 13:11:05.085 [13164] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 13:11:05.085 [13164] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 13:11:05.085 [13164] <2> async_connect: [vnet_connect.c:1477] connect in progress 1 0x1 13:11:05.086 [13164] <2> vnet_pbxConnect: pbxConnectEx Succeeded 13:11:05.086 [13164] <2> do_pbx_service: [vnet_connect.c:2116] via PBX bpdbm CONNECT FROM 10.132.113.128.51004 TO 10.132.112.22.1556 fd = 22 13:11:05.086 [13164] <2> async_connect: [vnet_connect.c:1644] connect async CONNECT FROM 10.132.113.128.51004 TO 10.132.112.22.1556 fd = 22 13:11:05.086 [13164] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO bfbackup.ipcmedia.com 10.132.112.22 bpdbm VIA pbx 13:11:05.086 [13164] <2> logconnections: BPDBM CONNECT FROM 10.132.113.128.51004 TO 10.132.112.22.1556 fd = 22 13:11:05.086 [13164] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2 13:11:05.090 [13164] <2> db_end: Need to collect reply 13:11:05.091 [13164] <4> report_client: VBRC 2 13164 1 lonbfbelvis1.corp.ad.timeinc.com_1389618685 29 ELVIS1-SNAP-TEST 0 Weekly-Full 0 1 1 13:11:05.091 [13164] <2> ConnectionCache::connectAndCache: Acquiring new connection for host bfbackup.ipcmedia.com, query type 81 13:11:05.091 [13164] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 13:11:05.091 [13164] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 13:11:05.091 [13164] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 13:11:05.091 [13164] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 13:11:05.091 [13164] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 13:11:05.091 [13164] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 13:11:05.091 [13164] <2> async_connect: [vnet_connect.c:1477] connect in progress 1 0x1 13:11:05.092 [13164] <2> vnet_pbxConnect: pbxConnectEx Succeeded 13:11:05.092 [13164] <2> do_pbx_service: [vnet_connect.c:2116] via PBX bpdbm CONNECT FROM 10.132.113.128.39952 TO 10.132.112.22.1556 fd = 22 13:11:05.092 [13164] <2> async_connect: [vnet_connect.c:1644] connect async CONNECT FROM 10.132.113.128.39952 TO 10.132.112.22.1556 fd = 22 13:11:05.092 [13164] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO bfbackup.ipcmedia.com 10.132.112.22 bpdbm VIA pbx 13:11:05.092 [13164] <2> logconnections: BPDBM CONNECT FROM 10.132.113.128.39952 TO 10.132.112.22.1556 fd = 22 13:11:05.092 [13164] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2 13:11:05.184 [13164] <2> db_end: Need to collect reply 13:11:05.184 [13164] <2> set_job_details: Tfile (233793): LOG 1389618665 4 bptm 13164 start backup 13:11:05.184 [13164] <2> send_job_file: job ID 233793, ftype = 3 msg len = 41, msg = LOG 1389618665 4 bptm 13164 start backup 13:11:05.184 [13164] <2> bp_sts_get_lsu: entry BFB-VMWARE-OST 13:11:05.184 [13164] <8> 233793:bptm:13164:lonbfbnbumedia.ipcmedia.com: [------ENTRY------] stspi_get_lsu_prop_byname_v11 13:11:05.190 [13164] <8> 233793:bptm:13164:lonbfbnbumedia.ipcmedia.com: [------EXIT------] stspi_get_lsu_prop_byname_v11 - 0 13:11:05.190 [13164] <2> ConnectionCache::connectAndCache: Acquiring new connection for host bfbackup.ipcmedia.com, query type 81 13:11:05.190 [13164] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 13:11:05.190 [13164] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 13:11:05.190 [13164] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 13:11:05.190 [13164] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 13:11:05.190 [13164] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 13:11:05.190 [13164] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 13:11:05.190 [13164] <2> async_connect: [vnet_connect.c:1477] connect in progress 1 0x1 13:11:05.191 [13164] <2> vnet_pbxConnect: pbxConnectEx Succeeded 13:11:05.191 [13164] <2> do_pbx_service: [vnet_connect.c:2116] via PBX bpdbm CONNECT FROM 10.132.113.128.43874 TO 10.132.112.22.1556 fd = 22 13:11:05.191 [13164] <2> async_connect: [vnet_connect.c:1644] connect async CONNECT FROM 10.132.113.128.43874 TO 10.132.112.22.1556 fd = 22 13:11:05.191 [13164] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO bfbackup.ipcmedia.com 10.132.112.22 bpdbm VIA pbx 13:11:05.191 [13164] <2> logconnections: BPDBM CONNECT FROM 10.132.113.128.43874 TO 10.132.112.22.1556 fd = 22 13:11:05.191 [13164] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2 13:11:05.285 [13164] <2> db_end: Need to collect reply 13:11:05.285 [13164] <4> write_disk_header: Data Classification & Storage Lifecycle action 4 13:11:05.285 [13164] <2> construct_sts_isid: master_server bfbackup.ipcmedia.com, client lonbfbelvis1.corp.ad.timeinc.com, backup_time 1389618685, copy_number 1, stream_number 1, fragment_number 0, resume_number 0, spl_name NULL 13:11:05.285 [13164] <2> bp_sts_open_image: STH set to STS_SA_IMAGE 13:11:05.285 [13164] <2> bp_sts_open_image: hp-StoreOnceCatalyst:10.132.126.163 BFB-VMWARE-OST lonbfbelvis1.corp.ad.timeinc.com_1389618685_C1_HDR 1 1 6 sth 0 2 8 LSU 0x2 0x1 0x2 saveas 0x4, stream_type 0x4 fscp 0 13:11:05.285 [13164] <8> 233793:bptm:13164:lonbfbnbumedia.ipcmedia.com: [------ENTRY------] stspi_create_image_v10 13:11:05.375 [13164] <2> 233793:bptm:13164:lonbfbnbumedia.ipcmedia.com: stspi_create_image_v10 : Image: lonbfbelvis1.corp.ad.timeinc.com_1389618685_C1_HDR Created. 13:11:05.375 [13164] <8> 233793:bptm:13164:lonbfbnbumedia.ipcmedia.com: [------EXIT------] stspi_create_image_v10 - 0 13:11:05.375 [13164] <8> 233793:bptm:13164:lonbfbnbumedia.ipcmedia.com: [------ENTRY------] stspi_get_image_prop_v10 13:11:05.399 [13164] <8> 233793:bptm:13164:lonbfbnbumedia.ipcmedia.com: [------EXIT------] stspi_get_image_prop_v10 - 0 13:11:05.399 [13164] <8> 233793:bptm:13164:lonbfbnbumedia.ipcmedia.com: [------ENTRY------] stspi_write_image 13:11:05.855 [13164] <8> 233793:bptm:13164:lonbfbnbumedia.ipcmedia.com: [------EXIT------] stspi_write_image - 0 13:11:05.855 [13164] <8> 233793:bptm:13164:lonbfbnbumedia.ipcmedia.com: [------ENTRY------] stspi_get_image_prop_v10 13:11:05.855 [13164] <8> 233793:bptm:13164:lonbfbnbumedia.ipcmedia.com: [------EXIT------] stspi_get_image_prop_v10 - 0 13:11:05.855 [13164] <2> bp_sts_close_image: 0xbcf6ab0 0 6 2 1 1 1 0 complete flag (64)(M_BACKUP_COMPLETE ), close type (5)(STS_CLOSEF_IH_COMPLETE STS_CLOSEF_IH_CPR ) 11 13:11:05.855 [13164] <8> 233793:bptm:13164:lonbfbnbumedia.ipcmedia.com: [------ENTRY------] stspi_close_image 13:11:06.304 [13164] <8> 233793:bptm:13164:lonbfbnbumedia.ipcmedia.com: [------EXIT------] stspi_close_image - 0 13:11:06.304 [13164] <2> construct_sts_isid: master_server bfbackup.ipcmedia.com, client lonbfbelvis1.corp.ad.timeinc.com, backup_time 1389618685, copy_number 1, stream_number 1, fragment_number 1, resume_number 0, spl_name NULL 13:11:06.304 [13164] <2> bp_sts_open_image: STH set to STS_SA_IMAGE 13:11:06.305 [13164] <2> bp_sts_open_image: hp-StoreOnceCatalyst:10.132.126.163 BFB-VMWARE-OST lonbfbelvis1.corp.ad.timeinc.com_1389618685_C1_F1 1 1 6 sth 0 2 8 LSU 0x2 0x1 0x2 saveas 0x4, stream_type 0x4 fscp 0 13:11:06.305 [13164] <8> 233793:bptm:13164:lonbfbnbumedia.ipcmedia.com: [------ENTRY------] stspi_create_image_v10 13:11:06.387 [13164] <2> 233793:bptm:13164:lonbfbnbumedia.ipcmedia.com: stspi_create_image_v10 : Image: lonbfbelvis1.corp.ad.timeinc.com_1389618685_C1_F1 Created. 13:11:06.387 [13164] <8> 233793:bptm:13164:lonbfbnbumedia.ipcmedia.com: [------EXIT------] stspi_create_image_v10 - 0 13:11:06.388 [13164] <8> 233793:bptm:13164:lonbfbnbumedia.ipcmedia.com: [------ENTRY------] stspi_get_image_prop_v10 13:11:06.411 [13164] <8> 233793:bptm:13164:lonbfbnbumedia.ipcmedia.com: [------EXIT------] stspi_get_image_prop_v10 - 0 13:11:06.411 [13164] <2> io_open_disk: file lonbfbelvis1.corp.ad.timeinc.com_1389618685_C1_F1 successfully opened 13:11:06.411 [13164] <2> bp_sts_is_filesystem_exported: Storage host 10.132.126.163 is not a nearstore 13:11:06.411 [13164] <2> ConnectionCache::connectAndCache: Acquiring new connection for host bfbackup.ipcmedia.com, query type 77 13:11:06.411 [13164] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 13:11:06.411 [13164] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 13:11:06.411 [13164] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 13:11:06.411 [13164] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 13:11:06.411 [13164] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 13:11:06.412 [13164] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 13:11:06.412 [13164] <2> async_connect: [vnet_connect.c:1477] connect in progress 1 0x1 13:11:06.412 [13164] <2> vnet_pbxConnect: pbxConnectEx Succeeded 13:11:06.412 [13164] <2> do_pbx_service: [vnet_connect.c:2116] via PBX bpdbm CONNECT FROM 10.132.113.128.35875 TO 10.132.112.22.1556 fd = 22 13:11:06.412 [13164] <2> async_connect: [vnet_connect.c:1644] connect async CONNECT FROM 10.132.113.128.35875 TO 10.132.112.22.1556 fd = 22 13:11:06.412 [13164] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO bfbackup.ipcmedia.com 10.132.112.22 bpdbm VIA pbx 13:11:06.412 [13164] <2> logconnections: BPDBM CONNECT FROM 10.132.113.128.35875 TO 10.132.112.22.1556 fd = 22 13:11:06.412 [13164] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2 13:11:06.417 [13164] <2> db_end: Need to collect reply 13:11:06.519 [13164] <2> add_disk_frag: added disk fragment for backupid lonbfbelvis1.corp.ad.timeinc.com_1389618685, copy 1, fragment 1 13:11:06.519 [13164] <2> add_disk_frag: media type = 0, media_subtype = 6 13:11:06.520 [13164] <2> write_backup: backup child process is pid 13174 13:11:06.520 [13164] <2> set_job_details: Tfile (233793): LOG 1389618666 4 bptm 13164 backup child process is pid 13174 13:11:06.520 [13164] <2> send_job_file: job ID 233793, ftype = 3 msg len = 62, msg = LOG 1389618666 4 bptm 13164 backup child process is pid 13174 13:11:06.520 [13164] <2> ConnectionCache::connectAndCache: Acquiring new connection for host bfbackup.ipcmedia.com, query type 1 13:11:06.520 [13174] <2> close_disk_descriptors: descriptor close: files = TRUE sockets = TRUE 13:11:06.520 [13164] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 13:11:06.520 [13164] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 13:11:06.520 [13164] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 13:11:06.520 [13164] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 13:11:06.520 [13164] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 13:11:06.520 [13164] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 13:11:06.520 [13164] <2> async_connect: [vnet_connect.c:1477] connect in progress 1 0x1 13:11:06.521 [13164] <2> vnet_pbxConnect: pbxConnectEx Succeeded 13:11:06.521 [13164] <2> do_pbx_service: [vnet_connect.c:2116] via PBX bpdbm CONNECT FROM 10.132.113.128.42907 TO 10.132.112.22.1556 fd = 0 13:11:06.521 [13164] <2> async_connect: [vnet_connect.c:1644] connect async CONNECT FROM 10.132.113.128.42907 TO 10.132.112.22.1556 fd = 0 13:11:06.521 [13164] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO bfbackup.ipcmedia.com 10.132.112.22 bpdbm VIA pbx 13:11:06.521 [13164] <2> logconnections: BPDBM CONNECT FROM 10.132.113.128.42907 TO 10.132.112.22.1556 fd = 0 13:11:06.521 [13164] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2 13:11:06.526 [13164] <2> db_end: Need to collect reply 13:11:06.527 [13164] <4> write_backup: begin writing backup id lonbfbelvis1.corp.ad.timeinc.com_1389618685, copy 1, fragment 1, destination path BFB-VMWARE-OST 13:11:06.527 [13164] <2> signal_parent: sending SIGUSR1 to bpbrm (pid = 13163) 13:11:06.527 [13164] <2> write_data: twin_index: 0 active: 1 dont_process: 0 wrote_backup_hdr: 0 finished_buff: 0 saved_cindex: -1 twin_is_disk 1 delay_brm: 0 13:11:06.527 [13164] <2> write_data: Total Kbytes transferred 0 13:11:06.527 [13164] <2> ndmp_setup_for_write: CINDEX 0, TWIN_INDEX 0, IS_NDMP 0, is_tir 0 13:11:10.502 [13164] <2> write_data: first write, twin_index: 0 cindex: 0 dont_process: 1 wrote_backup_hdr: 0 finished_buff: 0 13:11:10.502 [13164] <2> write_data: received first buffer (262144 bytes), begin writing data 13:11:10.502 [13164] <8> 233793:bptm:13164:lonbfbnbumedia.ipcmedia.com: [------ENTRY------] stspi_write_image 13:11:10.927 [13164] <8> 233793:bptm:13164:lonbfbnbumedia.ipcmedia.com: [------EXIT------] stspi_write_image - 0 13:11:10.927 [13164] <2> send_media_kbytes_written_establish_threshold: CINDEX 0, RB Kbytes for monitoring = 3000000 13:12:45.844 [13416] <2> SetMaxDataLimit: maximum data size: current=-1 max=-1 13:12:45.844 [13416] <2> initialize: fd values STDOUTSOCK=3 STDERRSOCK=4 13:12:45.848 [13416] <2> bptm: INITIATING (VERBOSE = 5): -rptdrv -jobid -1389612858 -jm 13:12:45.848 [13416] <2> bptm: PORT_STATUS = 0x00000000 13:12:45.848 [13416] <2> main: Sending [EXIT STATUS 0] to NBJM 13:12:45.848 [13416] <2> bptm: EXITING with status 0 <---------- 13:21:17.128 [13174] <2> insert_if_not_dup: [vnet_addrinfo.c:5724] ignoring IPv6 link local 0 0x0 13:21:17.128 [13174] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 13:21:17.128 [13174] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 13:21:17.128 [13174] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 13:21:17.128 [13174] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 13:21:17.128 [13174] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 13:21:17.128 [13174] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 13:21:17.128 [13174] <2> async_connect: [vnet_connect.c:1477] connect in progress 1 0x1 13:21:17.130 [13174] <2> vnet_pbxConnect: pbxConnectEx Succeeded 13:21:17.130 [13174] <2> do_pbx_service: [vnet_connect.c:2116] via PBX bpjobd CONNECT FROM 10.132.113.128.41283 TO 10.132.112.22.1556 fd = 1 13:21:17.130 [13174] <2> async_connect: [vnet_connect.c:1644] connect async CONNECT FROM 10.132.113.128.41283 TO 10.132.112.22.1556 fd = 1 13:21:17.130 [13174] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO bfbackup.ipcmedia.com 10.132.112.22 bpjobd VIA pbx 13:21:17.130 [13174] <2> job_connect: SO_KEEPALIVE set on socket 1 for client bfbackup.ipcmedia.com 13:21:17.130 [13174] <2> logconnections: BPJOBD CONNECT FROM 10.132.113.128.41283 TO 10.132.112.22.1556 fd = 1 13:21:17.130 [13174] <2> job_authenticate_connection: ignoring VxSS authentication check for now... 13:21:17.131 [13174] <2> job_connect: Connected to the host bfbackup.ipcmedia.com contype 53 jobid <233793> socket <1> 13:21:17.131 [13174] <2> job_connect: Connected on port 41283 13:21:17.131 [13174] <2> set_job_details: Tfile (233793): LOG 1389619277 16 bptm 13174 system call failed - Connection reset by peer (at child.c.1298) 13:21:17.131 [13174] <2> send_job_file: job ID 233793, ftype = 3 msg len = 93, msg = LOG 1389619277 16 bptm 13174 system call failed - Connection reset by peer (at child.c.1298) 13:21:17.135 [13164] <2> Media_dispatch_signal: calling catch_signal for 1 (bptm.c:23755) delay 0 seconds 13:21:17.135 [13164] <2> Media_siginfo_print: 0: delay 0 signo SIGHUP:1 code 0 pid 13163 13:21:17.175 [13174] <2> job_monitoring_exex: ACK disconnect 13:21:17.175 [13174] <2> job_disconnect: Disconnected 13:21:17.175 [13174] <2> ConnectionCache::connectAndCache: Acquiring new connection for host bfbackup.ipcmedia.com, query type 1 13:21:17.175 [13174] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 13:21:17.175 [13174] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 13:21:17.175 [13174] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 13:21:17.175 [13174] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 13:21:17.175 [13174] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 13:21:17.175 [13174] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 13:21:17.175 [13174] <2> async_connect: [vnet_connect.c:1477] connect in progress 1 0x1 13:21:17.176 [13174] <2> vnet_pbxConnect: pbxConnectEx Succeeded 13:21:17.176 [13174] <2> do_pbx_service: [vnet_connect.c:2116] via PBX bpdbm CONNECT FROM 10.132.113.128.41136 TO 10.132.112.22.1556 fd = 1 13:21:17.176 [13174] <2> async_connect: [vnet_connect.c:1644] connect async CONNECT FROM 10.132.113.128.41136 TO 10.132.112.22.1556 fd = 1 13:21:17.176 [13174] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO bfbackup.ipcmedia.com 10.132.112.22 bpdbm VIA pbx 13:21:17.176 [13174] <2> logconnections: BPDBM CONNECT FROM 10.132.113.128.41136 TO 10.132.112.22.1556 fd = 1 13:21:17.176 [13174] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2 13:21:17.180 [13174] <2> db_end: Need to collect reply 13:21:17.181 [13174] <16> fill_buffer: system call failed - Connection reset by peer (at child.c.1298) 13:21:17.181 [13174] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 13:21:17.181 [13174] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 13:21:17.181 [13174] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 13:21:17.181 [13174] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 13:21:17.181 [13174] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 13:21:17.181 [13174] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 13:21:17.181 [13174] <2> async_connect: [vnet_connect.c:1477] connect in progress 1 0x1 13:21:17.182 [13174] <2> vnet_pbxConnect: pbxConnectEx Succeeded 13:21:17.182 [13174] <2> do_pbx_service: [vnet_connect.c:2116] via PBX bpjobd CONNECT FROM 10.132.113.128.33953 TO 10.132.112.22.1556 fd = 1 13:21:17.182 [13174] <2> async_connect: [vnet_connect.c:1644] connect async CONNECT FROM 10.132.113.128.33953 TO 10.132.112.22.1556 fd = 1 13:21:17.182 [13174] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO bfbackup.ipcmedia.com 10.132.112.22 bpjobd VIA pbx 13:21:17.182 [13174] <2> job_connect: SO_KEEPALIVE set on socket 1 for client bfbackup.ipcmedia.com 13:21:17.182 [13174] <2> logconnections: BPJOBD CONNECT FROM 10.132.113.128.33953 TO 10.132.112.22.1556 fd = 1 13:21:17.182 [13174] <2> job_authenticate_connection: ignoring VxSS authentication check for now... 13:21:17.183 [13174] <2> job_connect: Connected to the host bfbackup.ipcmedia.com contype 53 jobid <233793> socket <1> 13:21:17.183 [13174] <2> job_connect: Connected on port 33953 13:21:17.183 [13174] <2> set_job_details: Tfile (233793): LOG 1389619277 16 bptm 13174 unable to perform read from client socket, connection may have been broken 13:21:17.183 [13174] <2> send_job_file: job ID 233793, ftype = 3 msg len = 104, msg = LOG 1389619277 16 bptm 13174 unable to perform read from client socket, connection may have been broken 13:21:17.227 [13174] <2> job_monitoring_exex: ACK disconnect 13:21:17.227 [13174] <2> job_disconnect: Disconnected 13:21:17.227 [13174] <2> ConnectionCache::connectAndCache: Acquiring new connection for host bfbackup.ipcmedia.com, query type 1 13:21:17.227 [13174] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 13:21:17.227 [13174] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 13:21:17.227 [13174] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 13:21:17.227 [13174] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 13:21:17.227 [13174] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 13:21:17.227 [13174] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 13:21:17.227 [13174] <2> async_connect: [vnet_connect.c:1477] connect in progress 1 0x1 13:21:17.228 [13174] <2> vnet_pbxConnect: pbxConnectEx Succeeded 13:21:17.228 [13174] <2> do_pbx_service: [vnet_connect.c:2116] via PBX bpdbm CONNECT FROM 10.132.113.128.50285 TO 10.132.112.22.1556 fd = 1 13:21:17.228 [13174] <2> async_connect: [vnet_connect.c:1644] connect async CONNECT FROM 10.132.113.128.50285 TO 10.132.112.22.1556 fd = 1 13:21:17.228 [13174] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO bfbackup.ipcmedia.com 10.132.112.22 bpdbm VIA pbx 13:21:17.228 [13174] <2> logconnections: BPDBM CONNECT FROM 10.132.113.128.50285 TO 10.132.112.22.1556 fd = 1 13:21:17.228 [13174] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2 13:21:17.232 [13174] <2> db_end: Need to collect reply 13:21:17.233 [13174] <16> fill_buffer: unable to perform read from client socket, connection may have been broken 13:21:17.233 [13174] <2> Evictor::~Evictor(): +++ ENTERING +++ : obj = 0x2b4f61eae670 (../Evictor.cpp:204) 13:21:17.233 [13174] <2> Evictor::shutdown(): +++ ENTERING +++ : obj = 0x2b4f61eae670 (../Evictor.cpp:580) 13:21:17.233 [13174] <2> Evictor::shutdown(): --- EXITING --- : obj = 0x2b4f61eae670 (../Evictor.cpp:580) 13:21:17.233 [13174] <2> Evictor::~Evictor(): --- EXITING --- : obj = 0x2b4f61eae670 (../Evictor.cpp:204) 13:21:17.234 [13174] <2> Evictor::~Evictor(): +++ ENTERING +++ : obj = 0xa71bd0 (../Evictor.cpp:204) 13:21:17.234 [13174] <2> Evictor::shutdown(): +++ ENTERING +++ : obj = 0xa71bd0 (../Evictor.cpp:580) 13:21:17.234 [13174] <2> Evictor::shutdown(): --- EXITING --- : obj = 0xa71bd0 (../Evictor.cpp:580) 13:21:17.234 [13174] <2> Evictor::~Evictor(): --- EXITING --- : obj = 0xa71bd0 (../Evictor.cpp:204) 13:21:17.234 [13174] <2> Evictor::~Evictor(): +++ ENTERING +++ : obj = 0xa79090 (../Evictor.cpp:204) 13:21:17.234 [13174] <2> Evictor::shutdown(): +++ ENTERING +++ : obj = 0xa79090 (../Evictor.cpp:580) 13:21:17.234 [13174] <2> Evictor::shutdown(): --- EXITING --- : obj = 0xa79090 (../Evictor.cpp:580) 13:21:17.234 [13174] <2> Evictor::~Evictor(): --- EXITING --- : obj = 0xa79090 (../Evictor.cpp:204) 13:21:17.235 [13174] <2> Orb::destroyObjectRef: destroying object reference(Orb.cpp:2246) 13:21:17.239 [13174] <2> BpConfigHolder::~BpConfigHolder: +++ ENTERING +++ : obj = 0xbc18128 (../BpConf.cpp:40) 13:21:17.239 [13174] <2> BpConfigHolder::free: +++ ENTERING +++ : obj = 0xbc18128 (../BpConf.cpp:60) 13:21:17.239 [13174] <2> BpConfigHolder::free: --- EXITING --- : obj = 0xbc18128 (../BpConf.cpp:60) 13:21:17.239 [13174] <2> BpConfigHolder::~BpConfigHolder: --- EXITING --- : obj = 0xbc18128 (../BpConf.cpp:40) 13:21:17.240 [13174] <2> TAO: ACE_Select_Reactor_Notify::notify [handle=20]: write to notification pipe handle failed: Bad file descriptor (9) 13:21:17.240 [13174] <2> NBIORInterceptor::destroy: Destroying Interceptor: _Default_CLIENT_Orb(NBIORInterceptor.cpp:61) 13:21:17.240 [13174] <2> TAO: ACE_Select_Reactor_Notify::notify [handle=13]: write to notification pipe handle failed: Bad file descriptor (9) 13:21:17.240 [13174] <2> NBIORInterceptor::destroy: Destroying Interceptor: NB_13164_708988832_Default_DAEMON_Orb(NBIORInterceptor.cpp:61) 13:21:17.240 [13174] <2> TAO: ACE_Select_Reactor_Notify::notify [handle=10]: write to notification pipe handle failed: Bad file descriptor (9) 13:21:17.240 [13174] <2> NBIORInterceptor::destroy: Destroying Interceptor: EMMlib_Orb_EMMlib_Orb(NBIORInterceptor.cpp:61) 13:21:17.241 [13174] <2> InsecureEndpointSelectorFactory::~InsecureEndpointSelectorFactory: +++ ENTERING +++ : obj = 0xbc1bb10 (../Endpoint_Selector.cpp:2082) 13:21:17.241 [13174] <2> InsecureEndpointSelectorFactory::~InsecureEndpointSelectorFactory: --- EXITING --- : obj = 0xbc1bb10 (../Endpoint_Selector.cpp:2082) 13:21:17.241 [13174] <2> EndpointSelectorFactory::~EndpointSelectorFactory: +++ ENTERING +++ : obj = 0xbc1b920 (../Endpoint_Selector.cpp:1987) 13:21:17.241 [13174] <2> EndpointSelectorFactory::~EndpointSelectorFactory: --- EXITING --- : obj = 0xbc1b920 (../Endpoint_Selector.cpp:1987) 13:21:17.243 [13164] <2> set_job_details: Tfile (233793): LOG 1389619277 16 bptm 13164 media manager terminated by parent process 13:21:17.243 [13164] <2> send_job_file: job ID 233793, ftype = 3 msg len = 72, msg = LOG 1389619277 16 bptm 13164 media manager terminated by parent process 13:21:17.243 [13164] <2> ConnectionCache::connectAndCache: Acquiring new connection for host bfbackup.ipcmedia.com, query type 1 13:21:17.243 [13164] <2> insert_if_not_dup: [vnet_addrinfo.c:5724] ignoring IPv6 link local 0 0x0 13:21:17.243 [13164] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 13:21:17.243 [13164] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 13:21:17.243 [13164] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 13:21:17.243 [13164] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 13:21:17.243 [13164] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 13:21:17.243 [13164] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 13:21:17.243 [13164] <2> async_connect: [vnet_connect.c:1477] connect in progress 1 0x1 13:21:17.244 [13164] <2> vnet_pbxConnect: pbxConnectEx Succeeded 13:21:17.244 [13164] <2> do_pbx_service: [vnet_connect.c:2116] via PBX bpdbm CONNECT FROM 10.132.113.128.51119 TO 10.132.112.22.1556 fd = 22 13:21:17.244 [13164] <2> async_connect: [vnet_connect.c:1644] connect async CONNECT FROM 10.132.113.128.51119 TO 10.132.112.22.1556 fd = 22 13:21:17.244 [13164] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO bfbackup.ipcmedia.com 10.132.112.22 bpdbm VIA pbx 13:21:17.244 [13164] <2> logconnections: BPDBM CONNECT FROM 10.132.113.128.51119 TO 10.132.112.22.1556 fd = 22 13:21:17.244 [13164] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2 13:21:17.248 [13164] <2> db_end: Need to collect reply 13:21:17.249 [13164] <16> catch_signal: media manager terminated by parent process 13:21:17.249 [13164] <2> KILL_MM_CHILD: Sending SIGUSR2 (kill) to child 13174 (tmcommon.c:1524) 13:21:17.249 [13164] <2> wait_for_sigcld: waiting for child to exit, timeout is 3600 13:21:17.249 [13164] <2> Media_siginfo_print: 1: delay 0 signo SIGCHLD:17 code 1 pid 13174 13:21:17.249 [13164] <2> child_wait: SIGCHLD: exit=42, signo=0 core=no, pid=13174 13:21:17.249 [13164] <8> 233793:bptm:13164:lonbfbnbumedia.ipcmedia.com: [------ENTRY------] stspi_get_image_prop_v10 13:21:17.249 [13164] <8> 233793:bptm:13164:lonbfbnbumedia.ipcmedia.com: [------EXIT------] stspi_get_image_prop_v10 - 0 13:21:17.249 [13164] <2> bp_sts_close_image: 0xbd56410 0 6 2 0 1 1 82 complete flag (8)(M_FRAG_COMPLETE ), close type (1)(STS_CLOSEF_IH_COMPLETE ) 11 13:21:17.249 [13164] <8> 233793:bptm:13164:lonbfbnbumedia.ipcmedia.com: [------ENTRY------] stspi_close_image 13:21:17.735 [13164] <8> 233793:bptm:13164:lonbfbnbumedia.ipcmedia.com: [------EXIT------] stspi_close_image - 0 13:21:17.735 [13164] <8> 233793:bptm:13164:lonbfbnbumedia.ipcmedia.com: [------ENTRY------] stspi_delete_image_v10 13:21:21.099 [13164] <2> 233793:bptm:13164:lonbfbnbumedia.ipcmedia.com: deleteImage : Image lonbfbelvis1.corp.ad.timeinc.com_1389618685_C1_F1 from lsu BFB-VMWARE-OST deleted. 13:21:21.099 [13164] <8> 233793:bptm:13164:lonbfbnbumedia.ipcmedia.com: [------EXIT------] stspi_delete_image_v10 - 0 13:21:21.099 [13164] <2> delete_image_disk_sts: Deleting disk header for 13:21:21.099 [13164] <8> 233793:bptm:13164:lonbfbnbumedia.ipcmedia.com: [------ENTRY------] stspi_delete_image_v10 13:21:24.761 [13164] <2> 233793:bptm:13164:lonbfbnbumedia.ipcmedia.com: deleteImage : Image lonbfbelvis1.corp.ad.timeinc.com_1389618685_C1_HDR from lsu BFB-VMWARE-OST deleted. 13:21:24.761 [13164] <8> 233793:bptm:13164:lonbfbnbumedia.ipcmedia.com: [------EXIT------] stspi_delete_image_v10 - 0 13:21:24.761 [13164] <2> ConnectionCache::connectAndCache: Acquiring new connection for host bfbackup.ipcmedia.com, query type 161 13:21:24.761 [13164] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 13:21:24.761 [13164] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 13:21:24.761 [13164] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 13:21:24.761 [13164] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 13:21:24.761 [13164] <2> vnet_sortaddrs: [vnet_addrinfo.c:3954] sorted addrs: 1 0x1 13:21:24.761 [13164] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 13:21:24.761 [13164] <2> async_connect: [vnet_connect.c:1477] connect in progress 1 0x1 13:21:24.762 [13164] <2> vnet_pbxConnect: pbxConnectEx Succeeded 13:21:24.762 [13164] <2> do_pbx_service: [vnet_connect.c:2116] via PBX bpdbm CONNECT FROM 10.132.113.128.44026 TO 10.132.112.22.1556 fd = 0 13:21:24.762 [13164] <2> async_connect: [vnet_connect.c:1644] connect async CONNECT FROM 10.132.113.128.44026 TO 10.132.112.22.1556 fd = 0 13:21:24.762 [13164] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO bfbackup.ipcmedia.com 10.132.112.22 bpdbm VIA pbx 13:21:24.762 [13164] <2> logconnections: BPDBM CONNECT FROM 10.132.113.128.44026 TO 10.132.112.22.1556 fd = 0 13:21:24.762 [13164] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2 13:21:24.767 [13164] <2> db_end: Need to collect reply 13:21:26.188 [13164] <2> send_MDS_msg: MEDIA_DONE 0 -1389612853 0 @aaaa8 0 0 {1CC774C4-7C54-11E3-8DAF-7048014D87CE} 13:21:26.188 [13164] <2> JobInst::sendIrmMsg: starting 13:21:26.188 [13164] <2> packageBptmResourceDoneMsg: msg (MEDIA_DONE 0 -1389612853 0 @aaaa8 0 0 {1CC774C4-7C54-11E3-8DAF-7048014D87CE}) 13:21:26.188 [13164] <2> packageBptmResourceDoneMsg: keyword MEDIA_DONE version 0 jobid -1389612853 copyNum 0 mediaId @aaaa8 mediaKey 0 unloadDelay 0 allocId {1CC774C4-7C54-11E3-8DAF-7048014D87CE} 13:21:26.188 [13164] <2> packageBptmResourceDoneMsg: returns 0 13:21:26.188 [13164] <2> EndpointSelector_R2::updateConfMembers: epsr2: bp.conf LastUpd=1389618602 bp.conf LastLook=1389618664(Endpoint_Selector.cpp:403) 13:21:26.188 [13164] <2> EndpointSelectorFactory::get_selector: epsr2: got 7.x EPS(Endpoint_Selector.cpp:2007) 13:21:26.188 [13164] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(Endpoint_Selector.cpp:530) 13:21:26.188 [13164] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Evaluating 2 endpoints(Endpoint_Selector.cpp:631) 13:21:26.188 [13164] <2> ConnectList::search: host 10.132.112.22 found GOOD(ConnectList.cpp:89) 13:21:26.188 [13164] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4828] using interface ANY 13:21:26.188 [13164] <2> EndpointSelector_R2::extractByAddressFamily: epsr2: address_binding: 10.132.112.22: ANY(Endpoint_Selector.cpp:1725) 13:21:26.188 [13164] <2> EndpointSelector_R2::cachedHostLookup: epsr2: Hostname 10.132.112.22 has 1 candidate addresses(Endpoint_Selector.cpp:1654) 13:21:26.188 [13164] <2> ConnectList::search: host 10.132.112.22 found GOOD(ConnectList.cpp:89) 13:21:26.188 [13164] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Reusing endpoint, target: 10.132.112.22, source: ANY(Endpoint_Selector.cpp:687) 13:21:26.188 [13164] <2> VxssContext::updateOnFailure: epsr2: updateOnFailure?=0 useVxss=2(Endpoint_Selector.cpp:243) 13:21:26.188 [13164] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure invocation->perform_call(...) to host 10.132.112.22 SUCCESS(Endpoint_Selector.cpp:1186) 13:21:26.188 [13164] <2> ConnectList::update: updated host 10.132.112.22 as GOOD, used 0 of 3 retries(ConnectList.cpp:175) 13:21:26.189 [13164] <2> JobInst::sendIrmMsg: returning 13:21:26.189 [13164] <2> catch_signal: EXITING with status 82 13:21:26.190 [13164] <8> 233793:bptm:13164:lonbfbnbumedia.ipcmedia.com: [------ENTRY------] stspi_get_server_prop 13:21:26.190 [13164] <8> 233793:bptm:13164:lonbfbnbumedia.ipcmedia.com: [------EXIT------] stspi_get_server_prop - 0 13:21:26.190 [13164] <8> 233793:bptm:13164:lonbfbnbumedia.ipcmedia.com: [------ENTRY------] stspi_close_server 13:21:26.190 [13164] <8> 233793:bptm:13164:lonbfbnbumedia.ipcmedia.com: [------EXIT------] stspi_close_server - 0 13:21:26.190 [13164] <2> cleanup: Detached from BPBRM shared memory 13:22:45.839 [14418] <2> SetMaxDataLimit: maximum data size: current=-1 max=-1 13:22:45.839 [14418] <2> initialize: fd values STDOUTSOCK=3 STDERRSOCK=4 13:22:45.843 [14418] <2> bptm: INITIATING (VERBOSE = 5): -rptdrv -jobid -1389612864 -jm 13:22:45.843 [14418] <2> bptm: PORT_STATUS = 0x00000000 13:22:45.843 [14418] <2> main: Sending [EXIT STATUS 0] to NBJM 13:22:45.843 [14418] <2> bptm: EXITING with status 0 <----------