17:56:04.673 [376966] <4> tpconfig: emmserver_name = hydra140 17:56:04.673 [376966] <4> tpconfig:main(): /usr/openv/volmgr/bin/tpconfig -add -storage_server mini-006 -sts_user_id test -password test -stype NEC_HYDRA 17:56:04.673 [376966] <2> mm_getnodename: cached_hostname hydra140, cached_method 3 17:56:04.673 [376966] <2> vnet_pcache_init_table: ../../libvlibs/vnet_private.c.232: 0: starting cache size: 200 0x000000c8 17:56:04.673 [376966] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1514: 0: found in file cache name: 192.168.157.46 17:56:04.673 [376966] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1515: 0: found in file cache service: NULL 17:56:04.673 [376966] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1514: 0: found in file cache name: 127.0.0.1 17:56:04.673 [376966] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1515: 0: found in file cache service: NULL 17:56:04.673 [376966] <2> insert_if_not_dup: ../../libvlibs/vnet_addrinfo.c.5771: 0: ignoring IPv6 loopback: 0 0x00000000 17:56:04.674 [376966] <2> mm_getnodename: (3) hostname hydra140 (from mm_master_config.mm_server_name) 17:56:04.674 [376966] <4> InitThisHostName: ThisHost is hydra140 17:56:04.674 [376966] <4> tpconfig: emmserver_port = 1556 17:56:04.680 [376966] <2> emmlib_initialize: (-) Connection attempt #<0> 17:56:04.680 [376966] <4> ValidateConnectionID: (-) Created new Connection ID 0 17:56:04.680 [376966] <2> emmlib_initializeEx: (-) Connecting to the Server Port <1556>, App OrbOpt 17:56:04.687 [376966] <2> Orb::setDebugLevelFromVxul: Orb logging configuration level set to 10(../Orb.cpp:2254) 17:56:04.687 [376966] <2> Orb::init: checking if NBAC is enabled(../Orb.cpp:509) 17:56:04.687 [376966] <2> BpConfReader::BpConfReader: +++ ENTERING +++ : obj = fffffffffff4110 (../BpConf.cpp:80) 17:56:04.687 [376966] <2> BpConfigHolder::BpConfigHolder: +++ ENTERING +++ : obj = 110120378 (../BpConf.cpp:34) 17:56:04.687 [376966] <2> BpConfigHolder::reload: +++ ENTERING +++ : obj = 110120378 (../BpConf.cpp:46) 17:56:04.687 [376966] <2> BpConfigHolder::reload: --- EXITING --- : obj = 110120378 (../BpConf.cpp:46) 17:56:04.687 [376966] <2> BpConfigHolder::BpConfigHolder: --- EXITING --- : obj = 110120378 (../BpConf.cpp:34) 17:56:04.687 [376966] <2> BpConfReader::BpConfReader: --- EXITING --- : obj = fffffffffff4110 (../BpConf.cpp:80) 17:56:04.687 [376966] <2> BpConfReader::~BpConfReader: +++ ENTERING +++ : obj = fffffffffff4110 (../BpConf.cpp:90) 17:56:04.687 [376966] <2> BpConfReader::~BpConfReader: --- EXITING --- : obj = fffffffffff4110 (../BpConf.cpp:90) 17:56:04.688 [376966] <2> EndpointSelectorFactory::EndpointSelectorFactory: +++ ENTERING +++ : obj = 110170b90 (../Endpoint_Selector.cpp:1704) 17:56:04.688 [376966] <2> BpConfReader::BpConfReader: +++ ENTERING +++ : obj = fffffffffff41a8 (../BpConf.cpp:80) 17:56:04.688 [376966] <2> BpConfReader::BpConfReader: --- EXITING --- : obj = fffffffffff41a8 (../BpConf.cpp:80) 17:56:04.688 [376966] <2> BpConfReader::~BpConfReader: +++ ENTERING +++ : obj = fffffffffff41a8 (../BpConf.cpp:90) 17:56:04.688 [376966] <2> BpConfReader::~BpConfReader: --- EXITING --- : obj = fffffffffff41a8 (../BpConf.cpp:90) 17:56:04.688 [376966] <2> EndpointSelector_R2::EndpointSelector_R2: +++ ENTERING +++ : obj = 110170c10 (../Endpoint_Selector.cpp:342) 17:56:04.688 [376966] <2> EndpointSelector_R2::updateConfMembers: +++ ENTERING +++ : obj = 110170c10 (../Endpoint_Selector.cpp:374) 17:56:04.688 [376966] <2> BpConfReader::BpConfReader: +++ ENTERING +++ : obj = fffffffffff3c08 (../BpConf.cpp:80) 17:56:04.688 [376966] <2> BpConfReader::BpConfReader: --- EXITING --- : obj = fffffffffff3c08 (../BpConf.cpp:80) 17:56:04.688 [376966] <2> EndpointSelector_R2::updateConfMembers: epsr2: tardy lookup log time value set to 5 seconds(../Endpoint_Selector.cpp:418) 17:56:04.688 [376966] <2> EndpointSelector_R2::updateConfMembers: epsr2: USE_VXSS = PROHIBITED(../Endpoint_Selector.cpp:429) 17:56:04.688 [376966] <2> BpConfReader::~BpConfReader: +++ ENTERING +++ : obj = fffffffffff3c08 (../BpConf.cpp:90) 17:56:04.688 [376966] <2> BpConfReader::~BpConfReader: --- EXITING --- : obj = fffffffffff3c08 (../BpConf.cpp:90) 17:56:04.688 [376966] <2> EndpointSelector_R2::updateConfMembers: --- EXITING --- : obj = 110170c10 (../Endpoint_Selector.cpp:374) 17:56:04.688 [376966] <2> EndpointSelector_R2::EndpointSelector_R2: --- EXITING --- : obj = 110170c10 (../Endpoint_Selector.cpp:342) 17:56:04.688 [376966] <2> EndpointSelectorFactory::EndpointSelectorFactory: Endpoint Selector model: 7.1 Flags: 0(../Endpoint_Selector.cpp:1751) 17:56:04.688 [376966] <2> EndpointSelectorFactory::EndpointSelectorFactory: --- EXITING --- : obj = 110170b90 (../Endpoint_Selector.cpp:1704) 17:56:04.688 [376966] <2> InsecureEndpointSelectorFactory::InsecureEndpointSelectorFactory: +++ ENTERING +++ : obj = 110170f30 (../Endpoint_Selector.cpp:1798) 17:56:04.688 [376966] <2> BpConfReader::BpConfReader: +++ ENTERING +++ : obj = fffffffffff41a8 (../BpConf.cpp:80) 17:56:04.688 [376966] <2> BpConfReader::BpConfReader: --- EXITING --- : obj = fffffffffff41a8 (../BpConf.cpp:80) 17:56:04.688 [376966] <2> BpConfReader::~BpConfReader: +++ ENTERING +++ : obj = fffffffffff41a8 (../BpConf.cpp:90) 17:56:04.688 [376966] <2> BpConfReader::~BpConfReader: --- EXITING --- : obj = fffffffffff41a8 (../BpConf.cpp:90) 17:56:04.688 [376966] <2> EndpointSelector_R2::EndpointSelector_R2: +++ ENTERING +++ : obj = 110170fb0 (../Endpoint_Selector.cpp:342) 17:56:04.688 [376966] <2> EndpointSelector_R2::updateConfMembers: +++ ENTERING +++ : obj = 110170fb0 (../Endpoint_Selector.cpp:374) 17:56:04.688 [376966] <2> BpConfReader::BpConfReader: +++ ENTERING +++ : obj = fffffffffff3c08 (../BpConf.cpp:80) 17:56:04.688 [376966] <2> BpConfReader::BpConfReader: --- EXITING --- : obj = fffffffffff3c08 (../BpConf.cpp:80) 17:56:04.688 [376966] <2> EndpointSelector_R2::updateConfMembers: epsr2: tardy lookup log time value set to 5 seconds(../Endpoint_Selector.cpp:418) 17:56:04.688 [376966] <2> EndpointSelector_R2::updateConfMembers: epsr2: USE_VXSS = PROHIBITED(../Endpoint_Selector.cpp:429) 17:56:04.688 [376966] <2> BpConfReader::~BpConfReader: +++ ENTERING +++ : obj = fffffffffff3c08 (../BpConf.cpp:90) 17:56:04.688 [376966] <2> BpConfReader::~BpConfReader: --- EXITING --- : obj = fffffffffff3c08 (../BpConf.cpp:90) 17:56:04.688 [376966] <2> EndpointSelector_R2::updateConfMembers: --- EXITING --- : obj = 110170fb0 (../Endpoint_Selector.cpp:374) 17:56:04.688 [376966] <2> EndpointSelector_R2::EndpointSelector_R2: --- EXITING --- : obj = 110170fb0 (../Endpoint_Selector.cpp:342) 17:56:04.688 [376966] <2> InsecureEndpointSelectorFactory::InsecureEndpointSelectorFactory: Endpoint Selector model: 7.1 Flags: 0 [Ignoring BPCONF server list](../Endpoint_Selector.cpp:1846) 17:56:04.688 [376966] <2> InsecureEndpointSelectorFactory::InsecureEndpointSelectorFactory: --- EXITING --- : obj = 110170f30 (../Endpoint_Selector.cpp:1798) 17:56:04.688 [376966] <2> checkService: found service: UTF16_BOM_Factory(../Orb.cpp:485) 17:56:04.688 [376966] <2> checkService: found service: UTF8-Latin1_Factory(../Orb.cpp:485) 17:56:04.688 [376966] <2> LogMaxFileNum: current max fileno: 8192, max fileno hard limit: 8192(../../libutil/SetMaxFileNum.cpp:112) 17:56:04.688 [376966] <2> Orb::getConfig: +++ ENTERING +++ : obj = 11011fe10 (../Orb.cpp:394) 17:56:04.688 [376966] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1514: 0: found in file cache name: NULL 17:56:04.688 [376966] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1515: 0: found in file cache service: veritas_pbx 17:56:04.688 [376966] <2> Orb::getConfig: found service entry: name = veritas_pbx port = 1556 proto = tcp(../Orb.cpp:403) 17:56:04.688 [376966] <2> BpConfReader::BpConfReader: +++ ENTERING +++ : obj = fffffffffff40e0 (../BpConf.cpp:80) 17:56:04.688 [376966] <2> BpConfReader::BpConfReader: --- EXITING --- : obj = fffffffffff40e0 (../BpConf.cpp:80) 17:56:04.688 [376966] <2> Orb::getConfig: cluster_name and required_interface not defined, using ANY(../Orb.cpp:455) 17:56:04.688 [376966] <2> BpConfReader::~BpConfReader: +++ ENTERING +++ : obj = fffffffffff40e0 (../BpConf.cpp:90) 17:56:04.689 [376966] <2> BpConfReader::~BpConfReader: --- EXITING --- : obj = fffffffffff40e0 (../BpConf.cpp:90) 17:56:04.689 [376966] <2> Orb::getConfig: --- EXITING --- : obj = 11011fe10 (../Orb.cpp:394) 17:56:04.689 [376966] <2> BpConfReader::BpConfReader: +++ ENTERING +++ : obj = fffffffffff3df0 (../BpConf.cpp:80) 17:56:04.689 [376966] <2> BpConfReader::BpConfReader: --- EXITING --- : obj = fffffffffff3df0 (../BpConf.cpp:80) 17:56:04.689 [376966] <2> BpConfReader::~BpConfReader: +++ ENTERING +++ : obj = fffffffffff3df0 (../BpConf.cpp:90) 17:56:04.689 [376966] <2> BpConfReader::~BpConfReader: --- EXITING --- : obj = fffffffffff3df0 (../BpConf.cpp:90) 17:56:04.689 [376966] <2> Orb::init: initializing ORB with: tpconfig -ORBSvcConfDirective "-ORBDottedDecimalAddresses 0" -ORBSvcConfDirective "static PBXIOP_Factory '-enable_keepalive'" -ORBSvcConfDirective "static EndpointSelectorFactory ''" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory PBXIOP_Factory'" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory IIOP_Factory'" -ORBDefaultInitRef '' -ORBSvcConfDirective "static PBXIOP_Evaluator_Factory '-orb EMMlib_Orb'"(../Orb.cpp:639) 17:56:04.689 [376966] <2> BpConfReader::BpConfReader: +++ ENTERING +++ : obj = fffffffffff4de0 (../BpConf.cpp:80) 17:56:04.689 [376966] <2> BpConfReader::BpConfReader: --- EXITING --- : obj = fffffffffff4de0 (../BpConf.cpp:80) 17:56:04.689 [376966] <2> BpConfReader::~BpConfReader: +++ ENTERING +++ : obj = fffffffffff4de0 (../BpConf.cpp:90) 17:56:04.689 [376966] <2> BpConfReader::~BpConfReader: --- EXITING --- : obj = fffffffffff4de0 (../BpConf.cpp:90) 17:56:04.689 [376966] <2> Orb::init: added args has value of: (../Orb.cpp:769) 17:56:04.689 [376966] <2> Orb::init: initializing ORB EMMlib_Orb with: tpconfig -ORBSvcConfDirective "-ORBDottedDecimalAddresses 0" -ORBSvcConfDirective "static PBXIOP_Factory '-enable_keepalive'" -ORBSvcConfDirective "static EndpointSelectorFactory ''" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory PBXIOP_Factory'" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory IIOP_Factory'" -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'" -ORBDebug -ORBDebugLevel 10(../Orb.cpp:824) 17:56:04.689 [376966] <2> TAO: starting up daemon tpconfig 17:56:04.689 [376966] <2> TAO: destroying TAO_POA, flags = 3 17:56:04.689 [376966] <2> TAO: Service_Config::process_directive - static PBXIOP_Factory '-enable_keepalive' 17:56:04.690 [376966] <2> TAO: opening static service PBXIOP_Factory 17:56:04.690 [376966] <2> TAO: did static on PBXIOP_Factory, error = 0 17:56:04.690 [376966] <2> TAO: Service_Config::process_directive - static EndpointSelectorFactory '' 17:56:04.690 [376966] <2> TAO: opening static service EndpointSelectorFactory 17:56:04.690 [376966] <2> TAO: did static on EndpointSelectorFactory, error = 0 17:56:04.690 [376966] <2> TAO: Service_Config::process_directive - static Resource_Factory '-ORBProtocolFactory PBXIOP_Factory' 17:56:04.690 [376966] <2> TAO: opening static service Resource_Factory 17:56:04.690 [376966] <2> TAO: did static on Resource_Factory, error = 0 17:56:04.690 [376966] <2> TAO: Service_Config::process_directive - static Resource_Factory '-ORBProtocolFactory IIOP_Factory' 17:56:04.690 [376966] <2> TAO: opening static service Resource_Factory 17:56:04.690 [376966] <2> TAO: did static on Resource_Factory, error = 0 17:56:04.690 [376966] <2> TAO: Service_Config::process_directive - static PBXIOP_Evaluator_Factory '-orb EMMlib_Orb' 17:56:04.690 [376966] <2> TAO: opening static service PBXIOP_Evaluator_Factory 17:56:04.690 [376966] <2> NBEvaluatorFactory::init: +++ ENTERING +++ : obj = 11014fc70 (../SecureEvaluator.cpp:498) 17:56:04.690 [376966] <2> NBEvaluatorFactory::init: --- EXITING --- : obj = 11014fc70 (../SecureEvaluator.cpp:498) 17:56:04.690 [376966] <2> TAO: did static on PBXIOP_Evaluator_Factory, error = 0 17:56:04.690 [376966] <2> TAO: Service_Config::process_directive - static Resource_Factory '-ORBConnectionCacheMax 1024 ' 17:56:04.690 [376966] <2> TAO: opening static service Resource_Factory 17:56:04.690 [376966] <2> TAO: did static on Resource_Factory, error = 0 17:56:04.690 [376966] <2> TAO: Service_Config::process_directive - static Server_Strategy_Factory '-ORBMaxRecvGIOPPayloadSize 268435456' 17:56:04.690 [376966] <2> TAO: opening static service Server_Strategy_Factory 17:56:04.690 [376966] <2> TAO: did static on Server_Strategy_Factory, error = 0 17:56:04.690 [376966] <2> NBOrbInitializer::pre_init: +++ ENTERING +++ : obj = 110173390 (../NBOrbInitializer.cpp:45) 17:56:04.690 [376966] <2> NBOrbInitializer::pre_init: --- EXITING --- : obj = 110173390 (../NBOrbInitializer.cpp:45) 17:56:04.690 [376966] <2> TAO: TAO (376966|1) - Loaded CodeSet , ncs = 00010001 tcs = 05010001 17:56:04.690 [376966] <2> TAO: ACE_Select_Reactor_Notify::open: notification pipe successfully opened with read_handle=6, write_handle=7 17:56:04.690 [376966] <2> TAO: TAO (376966|1) Loaded protocol 17:56:04.691 [376966] <2> TAO: TAO (376966|1) Loaded protocol 17:56:04.691 [376966] <2> NBOrbInitializer::post_init: +++ ENTERING +++ : obj = 110173390 (../NBOrbInitializer.cpp:53) 17:56:04.691 [376966] <2> NBOrbInitializer::post_init: Adding interceptors(../NBOrbInitializer.cpp:74) 17:56:04.691 [376966] <2> NBIORInterceptor::NBIORInterceptor: +++ ENTERING +++ : obj = 1101cc2b0 (../NBIORInterceptor.cpp:42) 17:56:04.691 [376966] <2> NBIORInterceptor::NBIORInterceptor: --- EXITING --- : obj = 1101cc2b0 (../NBIORInterceptor.cpp:42) 17:56:04.691 [376966] <2> NBIORInterceptor::name: +++ ENTERING +++ : obj = 1101cc2b0 (../NBIORInterceptor.cpp:55) 17:56:04.691 [376966] <2> NBIORInterceptor::name: --- EXITING --- : obj = 1101cc2b0 (../NBIORInterceptor.cpp:55) 17:56:04.691 [376966] <2> NBOrbInitializer::post_init: --- EXITING --- : obj = 110173390 (../NBOrbInitializer.cpp:53) 17:56:04.691 [376966] <2> TAO: TAO (376966|1) created new ORB 17:56:04.691 [376966] <2> Orb::init: caching EndpointSelectorFactory(../Orb.cpp:839) 17:56:04.691 [376966] <2> Orb::setOrbTimeoutPolicy: +++ ENTERING +++ : obj = 11011fe10 (../Orb.cpp:1499) 17:56:04.691 [376966] <2> Orb::setOrbTimeoutPolicy: setting ORB connection timeout policy: tv = 300(../Orb.cpp:1507) 17:56:04.691 [376966] <2> Orb::setOrbTimeoutPolicy: --- EXITING --- : obj = 11011fe10 (../Orb.cpp:1499) 17:56:04.691 [376966] <2> Orb::activate: +++ ENTERING +++ : obj = 11011fe10 (../Orb.cpp:1232) 17:56:04.691 [376966] <2> Orb::activate: Client Orb called activate; Ignored(../Orb.cpp:1237) 17:56:04.691 [376966] <2> Orb::activate: --- EXITING --- : obj = 11011fe10 (../Orb.cpp:1232) 17:56:04.691 [376966] <2> Orb::setOrbConnectTimeout: +++ ENTERING +++ : obj = 11011fe10 (../Orb.cpp:1480) 17:56:04.691 [376966] <2> Orb::setOrbTimeoutPolicy: +++ ENTERING +++ : obj = 11011fe10 (../Orb.cpp:1499) 17:56:04.691 [376966] <2> Orb::setOrbTimeoutPolicy: setting ORB connection timeout policy: tv = 60(../Orb.cpp:1507) 17:56:04.691 [376966] <2> Orb::setOrbTimeoutPolicy: --- EXITING --- : obj = 11011fe10 (../Orb.cpp:1499) 17:56:04.691 [376966] <2> Orb::setOrbConnectTimeout: --- EXITING --- : obj = 11011fe10 (../Orb.cpp:1480) 17:56:04.691 [376966] <2> emmlib_initializeEx: (-) Setting connect timeout to <60> sec 17:56:04.691 [376966] <2> Orb::setOrbRequestTimeout: +++ ENTERING +++ : obj = 11011fe10 (../Orb.cpp:1489) 17:56:04.691 [376966] <2> Orb::setOrbTimeoutPolicy: +++ ENTERING +++ : obj = 11011fe10 (../Orb.cpp:1499) 17:56:04.691 [376966] <2> Orb::setOrbTimeoutPolicy: setting ORB request timeout policy: tv = 1800(../Orb.cpp:1507) 17:56:04.691 [376966] <2> Orb::setOrbTimeoutPolicy: --- EXITING --- : obj = 11011fe10 (../Orb.cpp:1499) 17:56:04.691 [376966] <2> Orb::setOrbRequestTimeout: --- EXITING --- : obj = 11011fe10 (../Orb.cpp:1489) 17:56:04.691 [376966] <2> emmlib_initializeEx: (-) Setting request timeout to <1800> sec 17:56:04.691 [376966] <2> Orb::connectToObject: +++ ENTERING +++ : obj = 11011fe10 (../Orb.cpp:1127) 17:56:04.691 [376966] <2> BpConfReader::BpConfReader: +++ ENTERING +++ : obj = fffffffffff5760 (../BpConf.cpp:80) 17:56:04.691 [376966] <2> BpConfReader::BpConfReader: --- EXITING --- : obj = fffffffffff5760 (../BpConf.cpp:80) 17:56:04.691 [376966] <2> BpConfReader::~BpConfReader: +++ ENTERING +++ : obj = fffffffffff5760 (../BpConf.cpp:90) 17:56:04.691 [376966] <2> BpConfReader::~BpConfReader: --- EXITING --- : obj = fffffffffff5760 (../BpConf.cpp:90) 17:56:04.691 [376966] <2> Orb::connectToObject: Connection attempt #1 mW:60 rW:0(../Orb.cpp:1168) 17:56:04.691 [376966] <2> Orb::createInsecureObjectRef: +++ ENTERING +++ : obj = 11011fe10 (../Orb.cpp:1434) 17:56:04.691 [376966] <2> Orb::createInsecureObjectRef: corbaloc being used is: corbaloc:pbxiop:hydra140:1556:EMM/EMMServer(../Orb.cpp:1442) 17:56:04.691 [376966] <2> Orb::createInsecureObjectRef: --- EXITING --- : obj = 11011fe10 (../Orb.cpp:1434) 17:56:04.691 [376966] <2> Orb::connectToObjectOnce: attempt to create insecure connection(../Orb.cpp:1034) 17:56:04.691 [376966] <2> EndpointSelectorFactory::get_selector: +++ ENTERING +++ : obj = 110170b90 (../Endpoint_Selector.cpp:1765) 17:56:04.691 [376966] <2> EndpointSelector_R2::updateConfMembers: +++ ENTERING +++ : obj = 110170c10 (../Endpoint_Selector.cpp:374) 17:56:04.691 [376966] <2> EndpointSelector_R2::updateConfMembers: --- EXITING --- : obj = 110170c10 (../Endpoint_Selector.cpp:374) 17:56:04.691 [376966] <2> EndpointSelectorFactory::get_selector: --- EXITING --- : obj = 110170b90 (../Endpoint_Selector.cpp:1765) 17:56:04.691 [376966] <2> TAO: TAO (376966|1) RT Timeout is <1800000> 17:56:04.691 [376966] <2> EndpointSelector_R2::select_endpoint: +++ ENTERING +++ : obj = 110170c10 (../Endpoint_Selector.cpp:503) 17:56:04.691 [376966] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(../Endpoint_Selector.cpp:510) 17:56:04.691 [376966] <2> EndpointSelector_R2::endpoint_from_profile: +++ ENTERING +++ : obj = 110170c10 (../Endpoint_Selector.cpp:579) 17:56:04.691 [376966] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Evaluating 1 endpoints(../Endpoint_Selector.cpp:592) 17:56:04.692 [376966] <2> ConnectList::search: host hydra140 not found in cache(../ConnectList.cpp:116) 17:56:04.692 [376966] <2> VxssContext::VxssContext: +++ ENTERING +++ : obj = 110173cb8 (../Endpoint_Selector.cpp:116) 17:56:04.692 [376966] <2> VxssContext::VxssContext: --- EXITING --- : obj = 110173cb8 (../Endpoint_Selector.cpp:116) 17:56:04.692 [376966] <2> VxssContext::useVxss: +++ ENTERING +++ : obj = 110173cb8 (../Endpoint_Selector.cpp:129) 17:56:04.692 [376966] <2> VxssContext::useVxss: --- EXITING --- : obj = 110173cb8 (../Endpoint_Selector.cpp:129) 17:56:04.692 [376966] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1370: 0: found in cache name: 192.168.157.46 17:56:04.692 [376966] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1371: 0: found in cache service: NULL 17:56:04.692 [376966] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1370: 0: found in cache name: 127.0.0.1 17:56:04.692 [376966] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1371: 0: found in cache service: NULL 17:56:04.692 [376966] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1514: 0: found in file cache name: ::1 17:56:04.692 [376966] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1515: 0: found in file cache service: NULL 17:56:04.692 [376966] <2> VxssContext::begin: +++ ENTERING +++ : obj = 110173cb8 (../Endpoint_Selector.cpp:138) 17:56:04.692 [376966] <2> VxssContext::begin: --- EXITING --- : obj = 110173cb8 (../Endpoint_Selector.cpp:138) 17:56:04.692 [376966] <2> VxssContext::end: +++ ENTERING +++ : obj = 110173cb8 (../Endpoint_Selector.cpp:167) 17:56:04.692 [376966] <2> VxssContext::end: --- EXITING --- : obj = 110173cb8 (../Endpoint_Selector.cpp:167) 17:56:04.692 [376966] <2> VxssContext::secureMode: +++ ENTERING +++ : obj = 110173cb8 (../Endpoint_Selector.cpp:185) 17:56:04.692 [376966] <2> VxssContext::secureMode: --- EXITING --- : obj = 110173cb8 (../Endpoint_Selector.cpp:185) 17:56:04.692 [376966] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: pass: 0 secure_mode:2/0(../Endpoint_Selector.cpp:707) 17:56:04.692 [376966] <2> ConnectList::search: host hydra140 not found in cache(../ConnectList.cpp:116) 17:56:04.692 [376966] <2> VxssContext::secureMode: +++ ENTERING +++ : obj = 110173cb8 (../Endpoint_Selector.cpp:185) 17:56:04.692 [376966] <2> VxssContext::secureMode: --- EXITING --- : obj = 110173cb8 (../Endpoint_Selector.cpp:185) 17:56:04.692 [376966] <2> EndpointSelector_R2::evaluateEndpoint: +++ ENTERING +++ : obj = 110170c10 (../Endpoint_Selector.cpp:1340) 17:56:04.692 [376966] <2> EndpointSelector_R2::evaluateEndpoint: epsr2: PBXIOP endpoint host: hydra140(../Endpoint_Selector.cpp:1378) 17:56:04.692 [376966] <2> EndpointSelector_R2::cachedHostLookup: +++ ENTERING +++ : obj = 110170c10 (../Endpoint_Selector.cpp:1515) 17:56:04.692 [376966] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1514: 0: found in file cache name: hydra140 17:56:04.692 [376966] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1515: 0: found in file cache service: NULL 17:56:04.692 [376966] <2> EndpointSelector_R2::extractByAddressFamily: +++ ENTERING +++ : obj = 110170c10 (../Endpoint_Selector.cpp:1562) 17:56:04.692 [376966] <2> ConnectList::search: host 192.168.157.46 not found in cache(../ConnectList.cpp:116) 17:56:04.692 [376966] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1370: 0: found in cache name: 192.168.157.46 17:56:04.692 [376966] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1371: 0: found in cache service: NULL 17:56:04.692 [376966] <2> get_pref_netconnection: ../../libvlibs/vnet_addrinfo.c.4849: 0: Local [strong] check, using interface : ANY 17:56:04.692 [376966] <2> EndpointSelector_R2::extractByAddressFamily: epsr2: address_binding: 192.168.157.46: ANY(../Endpoint_Selector.cpp:1617) 17:56:04.693 [376966] <2> ConnectList::search: host 192.168.157.46 not found in cache(../ConnectList.cpp:116) 17:56:04.693 [376966] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1370: 0: found in cache name: 192.168.157.46 17:56:04.693 [376966] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1371: 0: found in cache service: NULL 17:56:04.693 [376966] <2> get_pref_netconnection: ../../libvlibs/vnet_addrinfo.c.4849: 0: Local [strong] check, using interface : ANY 17:56:04.693 [376966] <2> EndpointSelector_R2::extractByAddressFamily: epsr2: address_binding: 192.168.157.46: ANY(../Endpoint_Selector.cpp:1617) 17:56:04.693 [376966] <2> EndpointSelector_R2::extractByAddressFamily: --- EXITING --- : obj = 110170c10 (../Endpoint_Selector.cpp:1562) 17:56:04.693 [376966] <2> EndpointSelector_R2::extractByAddressFamily: +++ ENTERING +++ : obj = 110170c10 (../Endpoint_Selector.cpp:1562) 17:56:04.693 [376966] <2> EndpointSelector_R2::extractByAddressFamily: --- EXITING --- : obj = 110170c10 (../Endpoint_Selector.cpp:1562) 17:56:04.693 [376966] <2> EndpointSelector_R2::cachedHostLookup: epsr2: Hostname hydra140 has 2 candidate addresses(../Endpoint_Selector.cpp:1550) 17:56:04.693 [376966] <2> EndpointSelector_R2::cachedHostLookup: --- EXITING --- : obj = 110170c10 (../Endpoint_Selector.cpp:1515) 17:56:04.693 [376966] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1514: 0: found in file cache name: hydra140 17:56:04.693 [376966] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1515: 0: found in file cache service: NULL 17:56:04.693 [376966] <2> EndpointSelector_R2::evaluateEndpoint: epsr2: Found 2 usable IP addresses for host hydra140:[192.168.157.46] [192.168.157.46] (../Endpoint_Selector.cpp:1501) 17:56:04.693 [376966] <2> EndpointSelector_R2::evaluateEndpoint: --- EXITING --- : obj = 110170c10 (../Endpoint_Selector.cpp:1340) 17:56:04.693 [376966] <2> VxssContext::secureMode: +++ ENTERING +++ : obj = 110173cb8 (../Endpoint_Selector.cpp:185) 17:56:04.693 [376966] <2> VxssContext::secureMode: --- EXITING --- : obj = 110173cb8 (../Endpoint_Selector.cpp:185) 17:56:04.693 [376966] <2> EndpointSelector_R2::performCallUsingEndpointAndSourceBinding: +++ ENTERING +++ : obj = 110170c10 (../Endpoint_Selector.cpp:826) 17:56:04.693 [376966] <2> ConnectList::search: host 192.168.157.46 not found in cache(../ConnectList.cpp:116) 17:56:04.693 [376966] <2> ConnectList::search: host 192.168.157.46 not found in cache(../ConnectList.cpp:116) 17:56:04.693 [376966] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1514: 0: found in file cache name: 192.168.157.46 17:56:04.693 [376966] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1515: 0: found in file cache service: NULL 17:56:04.693 [376966] <2> ExternalResolver::set: Resolved host_name 192.168.157.46[192.168.157.46](../../libutil/ExternalResolver.cpp:56) 17:56:04.693 [376966] <2> EndpointSelector_R2::performCallUsingEndpointAndSourceBinding: epsr2: Target: 192.168.157.46, source: ANY(../Endpoint_Selector.cpp:897) 17:56:04.693 [376966] <2> VxssContext::updateOnFailure: +++ ENTERING +++ : obj = 110173cb8 (../Endpoint_Selector.cpp:192) 17:56:04.693 [376966] <2> VxssContext::updateOnFailure: --- EXITING --- : obj = 110173cb8 (../Endpoint_Selector.cpp:192) 17:56:04.693 [376966] <2> EndpointSelector_R2::performCallUsingEndpoint: +++ ENTERING +++ : obj = 110170c10 (../Endpoint_Selector.cpp:950) 17:56:04.693 [376966] <2> TAO: TAO (376966|1) Connection Timeout is <0> 17:56:04.693 [376966] <2> TAO: TAO (376966|1) - Transport_Cache_Manager::find_i, unable to locate a free connection 17:56:04.693 [376966] <2> TAO: TAO (376966|1) - Transport_Cache_Manager::fill_set_i, current_size = 0, cache_maximum = 1024 17:56:04.693 [376966] <2> TAO: PBXIOP (376966|1) - PBXIOP_Connector::make_connection, to <[192.168.157.46]:1556:EMM> 17:56:04.693 [376966] <2> TAO: PBXIOP (376966|1) PBXIOP_Connector::make_local_address using local addr [0.0.0.0]:0 17:56:04.693 [376966] <2> TAO: (376966|1) PBXIOP_Connector::make_connection, attempting local bind to 0 17:56:04.693 [376966] <2> TAO: PBXIOP (376966|1) - PBXIOP_Connection_Handler::PBXIOP_Connection_Handler, socket keepalive is 1 17:56:04.694 [376966] <2> TAO: PBXIOP (376966|1) - PBXIOP_Connection_Handler::open, enabled socket keepalive 17:56:04.694 [376966] <2> TAO: PBXIOP (376966|1) PBXIOP connection to peer <[192.168.157.46]:1556> on 8 17:56:04.694 [376966] <2> TAO: PBXIOP (376966|1) - PBXIOP_Connector::make_connection, new connection to <192.168.157.46:1556:EMM> on Transport[8] 17:56:04.694 [376966] <2> TAO: TAO (376966|1) - Transport_Cache_Manager::bind_i, 0xffff1d68 -> 0x101cd9d0 Transport[8] 17:56:04.694 [376966] <2> TAO: TAO (376966|1) - Transport_Cache_Manager::bind_i, size is [1] 17:56:04.694 [376966] <2> TAO: PBXIOP (376966|1) PBXIOP_Transport[8]::register_handler 17:56:04.694 [376966] <2> TAO: TAO (376966|1) - Transport_Connection::connect, opening Transport[8] in TAO_CLIENT_ROLE 17:56:04.694 [376966] <2> TAO: TAO (376966|1) - Codeset_Manager::set_tcs No codeset context received, using defaults 17:56:04.694 [376966] <2> TAO: TAO (376966|1) - Using utf16 BOM translator 17:56:04.694 [376966] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure invocation->perform_call(...) to host 192.168.157.46 SUCCESS(../Endpoint_Selector.cpp:1108) 17:56:04.694 [376966] <2> EndpointSelector_R2::performCallUsingEndpoint: --- EXITING --- : obj = 110170c10 (../Endpoint_Selector.cpp:950) 17:56:04.694 [376966] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1370: 0: found in cache name: 192.168.157.46 17:56:04.694 [376966] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1371: 0: found in cache service: NULL 17:56:04.695 [376966] <2> ConnectList::update: added host 192.168.157.46 (1 total) as GOOD(../ConnectList.cpp:211) 17:56:04.695 [376966] <2> EndpointSelector_R2::performCallUsingEndpointAndSourceBinding: --- EXITING --- : obj = 110170c10 (../Endpoint_Selector.cpp:826) 17:56:04.695 [376966] <2> VxssContext::next: +++ ENTERING +++ : obj = 110173cb8 (../Endpoint_Selector.cpp:174) 17:56:04.695 [376966] <2> VxssContext::next: --- EXITING --- : obj = 110173cb8 (../Endpoint_Selector.cpp:174) 17:56:04.695 [376966] <2> VxssContext::end: +++ ENTERING +++ : obj = 110173cb8 (../Endpoint_Selector.cpp:167) 17:56:04.695 [376966] <2> VxssContext::end: --- EXITING --- : obj = 110173cb8 (../Endpoint_Selector.cpp:167) 17:56:04.695 [376966] <2> EndpointSelector_R2::endpoint_from_profile: --- EXITING --- : obj = 110170c10 (../Endpoint_Selector.cpp:579) 17:56:04.695 [376966] <2> EndpointSelector_R2::select_endpoint: --- EXITING --- : obj = 110170c10 (../Endpoint_Selector.cpp:503) 17:56:04.695 [376966] <2> TAO: TAO (376966|1) - Muxed_TMS[8]::request_id, <1> 17:56:04.695 [376966] <2> TAO: TAO (376966|1) - UTF16_BOM_Translator:forceBE 0 17:56:04.695 [376966] <2> TAO: TAO (376966|1) - Codeset_Manager::generate_service_context using tcs_c = 00010001, tcs_w = 00010109 17:56:04.695 [376966] <2> TAO: TAO (376966|1) - GIOP_Message_Base::dump_msg, send GIOP v1.2 msg, 76 data bytes, my endian, Type Request[1] 17:56:04.695 [376966] <2> TAO: TAO (376966|1) - GIOP message - HEXDUMP 88 bytes 47 49 4f 50 01 02 00 00 00 00 00 4c 00 00 00 01 GIOP.......L.... 03 00 00 00 00 00 46 40 00 00 00 09 45 4d 4d 53 ......F@....EMMS 65 72 76 65 72 0c 95 10 00 00 00 0e 5f 6e 6f 6e erver......._non 5f 65 78 69 73 74 65 6e 74 00 10 00 00 00 00 01 _existent....... 00 00 00 01 00 00 00 0c 00 00 00 00 00 01 00 01 ................ 00 01 01 09 00 00 00 09 ........ 17:56:04.695 [376966] <2> TAO: PBXIOP (376966|1) - PBXIOP_Transport::send_message_shared 17:56:04.695 [376966] <2> TAO: TAO (376966|1) - Transport[8]::cleanup_queue, byte_count = 88 17:56:04.695 [376966] <2> TAO: TAO (376966|1) - Transport[8]::cleanup_queue, after transfer, bc = 0, all_sent = 1, ml = 0 17:56:04.695 [376966] <2> TAO: TAO (376966|1) - Transport[8]::drain_queue_helper, byte_count = 88, head_is_empty = 1 17:56:04.695 [376966] <2> TAO: TAO (376966|1) - Transport[8]::drain_queue_i, helper retval = 1 17:56:04.695 [376966] <2> EndpointSelector_R2::success: +++ ENTERING +++ : obj = 110170c10 (../Endpoint_Selector.cpp:557) 17:56:04.695 [376966] <2> EndpointSelector_R2::success: --- EXITING --- : obj = 110170c10 (../Endpoint_Selector.cpp:557) 17:56:04.695 [376966] <2> TAO: TAO (376966|1) - Synch_Invocation::invoke_i, timeout on recv is <1799996> 17:56:04.695 [376966] <2> TAO: TAO (376966|1) - Leader_Follower[8]::wait_for_event, (leader) enter reactor event loop 17:56:04.699 [376966] <2> TAO: TAO (376966|1) - Transport[8]::acts_as_server() opened as TAO_CLIENT_ROLE, bidir_flag=-1, returning 0 17:56:04.699 [376966] <2> TAO: TAO (376966|1) - Connection_Handler[8]::handle_input, handle = 8/8 17:56:04.699 [376966] <2> TAO: TAO (376966|1) - Transport[8]::handle_input 17:56:04.699 [376966] <2> TAO: TAO (376966|1) - Transport[8]::process_queue_head 17:56:04.699 [376966] <2> TAO: TAO (376966|1) - Transport[8]::handle_input: read 292 bytes 17:56:04.699 [376966] <2> TAO: TAO (376966|1) - Transport::handle_input(): bytes read from socket - HEXDUMP 292 bytes 47 49 4f 50 01 02 00 01 00 00 01 18 00 00 00 01 GIOP............ 00 00 00 03 00 00 00 00 00 00 00 1b 49 44 4c 3a ............IDL: 56 65 72 69 74 61 73 2f 45 4d 4d 2f 53 65 72 76 Veritas/EMM/Serv 65 72 3a 31 2e 30 00 48 00 00 00 01 4f 43 49 01 er:1.0.H....OCI. 00 00 00 e0 00 01 02 00 00 00 00 09 68 79 64 72 ............hydr 61 31 34 30 00 30 06 14 00 00 00 04 45 4d 4d 00 a140.0......EMM. 00 00 00 32 14 01 0f 00 4e 55 50 00 00 00 16 00 ...2....NUP..... 00 00 01 00 00 00 01 00 45 4d 4d 53 65 72 76 65 ........EMMServe 72 50 4f 41 00 00 00 00 00 00 00 00 01 45 4d 4d rPOA.........EMM 53 65 72 76 65 72 30 31 00 00 00 04 00 00 00 00 Server01........ 00 00 00 08 00 00 00 01 54 41 4f 00 00 00 00 01 ........TAO..... 00 00 00 18 00 00 00 01 00 01 00 01 00 00 00 01 ................ 05 01 00 01 00 01 01 09 00 00 00 00 00 00 00 03 .............. 17:56:04.699 [376966] <2> TAO: TAO (376966|1) - Transport::handle_input: extracting complete messages 17:56:04.699 [376966] <2> TAO: TAO (376966|1) - from this message buffer - HEXDUMP 292 bytes 47 49 4f 50 01 02 00 01 00 00 01 18 00 00 00 01 GIOP............ 00 00 00 03 00 00 00 00 00 00 00 1b 49 44 4c 3a ............IDL: 56 65 72 69 74 61 73 2f 45 4d 4d 2f 53 65 72 76 Veritas/EMM/Serv 65 72 3a 31 2e 30 00 48 00 00 00 01 4f 43 49 01 er:1.0.H....OCI. 00 00 00 e0 00 01 02 00 00 00 00 09 68 79 64 72 ............hydr 61 31 34 30 00 30 06 14 00 00 00 04 45 4d 4d 00 a140.0......EMM. 00 00 00 32 14 01 0f 00 4e 55 50 00 00 00 16 00 ...2....NUP..... 00 00 01 00 00 00 01 00 45 4d 4d 53 65 72 76 65 ........EMMServe 72 50 4f 41 00 00 00 00 00 00 00 00 01 45 4d 4d rPOA.........EMM 53 65 72 76 65 72 30 31 00 00 00 04 00 00 00 00 Server01........ 00 00 00 08 00 00 00 01 54 41 4f 00 00 00 00 01 ........TAO..... 00 00 00 18 00 00 00 01 00 01 00 01 00 00 00 01 ................ 05 01 00 01 00 01 01 09 00 00 00 00 00 00 00 03 ................ 00 00 00 24 00 00 ... 17:56:04.699 [376966] <2> TAO: TAO (376966|1) - Queued_Data::make_complete_message: extracted complete message (292 bytes incl hdr) from mblk=ffff3140 into qd=101cd350 17:56:04.699 [376966] <2> TAO: TAO (376966|1) - Transport::handle_input: extracting complete messages 17:56:04.699 [376966] <2> TAO: TAO (376966|1) - from this message buffer - HEXDUMP 0 bytes 17:56:04.699 [376966] <2> TAO: TAO (376966|1) - Transport[8]::process_queue_head 17:56:04.699 [376966] <2> TAO: TAO (376966|1) - Transport[8]::process_queue_head, the size of the queue is [0] 17:56:04.699 [376966] <2> TAO: TAO (376966|1) - Resume_Handle[8]::resume_handle, handle resumed 17:56:04.699 [376966] <2> TAO: TAO (376966|1) - GIOP_Message_Base::dump_msg, recv GIOP v1.2 msg, 280 data bytes, my endian, Type Reply[1] 17:56:04.699 [376966] <2> TAO: TAO (376966|1) - GIOP message - HEXDUMP 292 bytes 47 49 4f 50 01 02 00 01 00 00 01 18 00 00 00 01 GIOP............ 00 00 00 03 00 00 00 00 00 00 00 1b 49 44 4c 3a ............IDL: 56 65 72 69 74 61 73 2f 45 4d 4d 2f 53 65 72 76 Veritas/EMM/Serv 65 72 3a 31 2e 30 00 48 00 00 00 01 4f 43 49 01 er:1.0.H....OCI. 00 00 00 e0 00 01 02 00 00 00 00 09 68 79 64 72 ............hydr 61 31 34 30 00 30 06 14 00 00 00 04 45 4d 4d 00 a140.0......EMM. 00 00 00 32 14 01 0f 00 4e 55 50 00 00 00 16 00 ...2....NUP..... 00 00 01 00 00 00 01 00 45 4d 4d 53 65 72 76 65 ........EMMServe 72 50 4f 41 00 00 00 00 00 00 00 00 01 45 4d 4d rPOA.........EMM 53 65 72 76 65 72 30 31 00 00 00 04 00 00 00 00 Server01........ 00 00 00 08 00 00 00 01 54 41 4f 00 00 00 00 01 ........TAO..... 00 00 00 18 00 00 00 01 00 01 00 01 00 00 00 01 ................ 05 01 00 01 00 01 01 09 00 00 00 00 00 00 00 03 ................ 00 00 00 24 00 00 00 01 00 00 ... 17:56:04.699 [376966] <2> TAO: TAO (376966|1)- TAO_Muxed_TMS::dispatch_reply, id = 1 17:56:04.699 [376966] <2> TAO: TAO (376966|1) - Connection_Handler[8]::handle_input, handle = 8/8, retval = 0 17:56:04.699 [376966] <2> TAO: TAO (376966|1) - Leader_Follower[8]::wait_for_event, (leader) exit reactor event loop 17:56:04.699 [376966] <2> TAO: TAO (376966|1) Synch_Invocation::invoke_i, timeout after recv is <1799987> status <1> 17:56:04.699 [376966] <2> EndpointSelector_R2::forward: +++ ENTERING +++ : obj = 110170c10 (../Endpoint_Selector.cpp:534) 17:56:04.699 [376966] <2> EndpointSelector_R2::forward: epsr2: Adding 1 forward profiles(../Endpoint_Selector.cpp:536) 17:56:04.699 [376966] <2> EndpointSelector_R2::forward: --- EXITING --- : obj = 110170c10 (../Endpoint_Selector.cpp:534) 17:56:04.699 [376966] <2> TAO: TAO (376966|1) Return value from unbind_dispatcher is [-1] 17:56:04.699 [376966] <2> EndpointSelector_R2::select_endpoint: +++ ENTERING +++ : obj = 110170c10 (../Endpoint_Selector.cpp:503) 17:56:04.699 [376966] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(../Endpoint_Selector.cpp:510) 17:56:04.699 [376966] <2> EndpointSelector_R2::endpoint_from_profile: +++ ENTERING +++ : obj = 110170c10 (../Endpoint_Selector.cpp:579) 17:56:04.699 [376966] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Evaluating 3 endpoints(../Endpoint_Selector.cpp:592) 17:56:04.699 [376966] <2> ConnectList::search: host hydra140 not found in cache(../ConnectList.cpp:116) 17:56:04.699 [376966] <2> ConnectList::search: host 192.168.157.46 found GOOD(../ConnectList.cpp:89) 17:56:04.699 [376966] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Reusing endpoint, target: 192.168.157.46, source: ANY(../Endpoint_Selector.cpp:629) 17:56:04.699 [376966] <2> VxssContext::updateOnFailure: +++ ENTERING +++ : obj = 110173cb8 (../Endpoint_Selector.cpp:192) 17:56:04.699 [376966] <2> VxssContext::updateOnFailure: --- EXITING --- : obj = 110173cb8 (../Endpoint_Selector.cpp:192) 17:56:04.699 [376966] <2> EndpointSelector_R2::performCallUsingEndpoint: +++ ENTERING +++ : obj = 110170c10 (../Endpoint_Selector.cpp:950) 17:56:04.699 [376966] <2> TAO: TAO (376966|1) Connection Timeout is <0> 17:56:04.700 [376966] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1370: 0: found in cache name: 192.168.157.46 17:56:04.700 [376966] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1371: 0: found in cache service: NULL 17:56:04.700 [376966] <2> ExternalResolver::set: Resolved host_name 192.168.157.46[192.168.157.46](../../libutil/ExternalResolver.cpp:56) 17:56:04.700 [376966] <2> TAO: TAO (376966|1) - Transport_Cache_Manager::is_entry_idle_i, state is [0] 17:56:04.700 [376966] <2> TAO: TAO (376966|1) - Transport_Cache_Manager::find_i, index in find <0> (Transport[8]) 17:56:04.700 [376966] <2> TAO: TAO (376966|1) - Transport_Connector::connect, got an existing Transport[8] in role TAO_CLIENT_ROLE 17:56:04.700 [376966] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure invocation->perform_call(...) to host 192.168.157.46 SUCCESS(../Endpoint_Selector.cpp:1108) 17:56:04.700 [376966] <2> EndpointSelector_R2::performCallUsingEndpoint: --- EXITING --- : obj = 110170c10 (../Endpoint_Selector.cpp:950) 17:56:04.700 [376966] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1370: 0: found in cache name: 192.168.157.46 17:56:04.700 [376966] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1371: 0: found in cache service: NULL 17:56:04.700 [376966] <2> ConnectList::update: updated host 192.168.157.46 as GOOD, used 0 of 3 retries(../ConnectList.cpp:175) 17:56:04.700 [376966] <2> EndpointSelector_R2::endpoint_from_profile: --- EXITING --- : obj = 110170c10 (../Endpoint_Selector.cpp:579) 17:56:04.700 [376966] <2> EndpointSelector_R2::select_endpoint: --- EXITING --- : obj = 110170c10 (../Endpoint_Selector.cpp:503) 17:56:04.700 [376966] <2> TAO: TAO (376966|1) - Muxed_TMS[8]::request_id, <2> 17:56:04.700 [376966] <2> TAO: TAO (376966|1) - Codeset_Manager::generate_service_context using tcs_c = 00010001, tcs_w = 00010109 17:56:04.700 [376966] <2> TAO: TAO (376966|1) - GIOP_Message_Base::dump_msg, send GIOP v1.2 msg, 116 data bytes, my endian, Type Request[2] 17:56:04.700 [376966] <2> TAO: TAO (376966|1) - GIOP message - HEXDUMP 128 bytes 47 49 4f 50 01 02 00 00 00 00 00 74 00 00 00 02 GIOP.......t.... 03 00 00 00 00 00 46 40 00 00 00 32 14 01 0f 00 ......F@...2.... 4e 55 50 00 00 00 16 00 00 00 01 00 00 00 01 00 NUP............. 45 4d 4d 53 65 72 76 65 72 50 4f 41 00 00 00 00 EMMServerPOA.... 00 00 00 00 01 45 4d 4d 53 65 72 76 65 72 00 01 .....EMMServer.. 00 00 00 0e 5f 6e 6f 6e 5f 65 78 69 73 74 65 6e ...._non_existen 74 00 ff ff 00 00 00 01 00 00 00 01 00 00 00 0c t............... 00 00 00 00 00 01 00 01 00 01 01 09 a0 07 b1 70 ...............p 17:56:04.700 [376966] <2> TAO: PBXIOP (376966|1) - PBXIOP_Transport::send_message_shared 17:56:04.700 [376966] <2> TAO: TAO (376966|1) - Transport[8]::cleanup_queue, byte_count = 128 17:56:04.700 [376966] <2> TAO: TAO (376966|1) - Transport[8]::cleanup_queue, after transfer, bc = 0, all_sent = 1, ml = 0 17:56:04.700 [376966] <2> TAO: TAO (376966|1) - Transport[8]::drain_queue_helper, byte_count = 128, head_is_empty = 1 17:56:04.700 [376966] <2> TAO: TAO (376966|1) - Transport[8]::drain_queue_i, helper retval = 1 17:56:04.700 [376966] <2> EndpointSelector_R2::success: +++ ENTERING +++ : obj = 110170c10 (../Endpoint_Selector.cpp:557) 17:56:04.700 [376966] <2> EndpointSelector_R2::success: --- EXITING --- : obj = 110170c10 (../Endpoint_Selector.cpp:557) 17:56:04.700 [376966] <2> TAO: TAO (376966|1) - Synch_Invocation::invoke_i, timeout on recv is <1799987> 17:56:04.700 [376966] <2> TAO: TAO (376966|1) - Leader_Follower[8]::wait_for_event, (leader) enter reactor event loop 17:56:04.702 [376966] <2> TAO: TAO (376966|1) - Transport[8]::acts_as_server() opened as TAO_CLIENT_ROLE, bidir_flag=-1, returning 0 17:56:04.702 [376966] <2> TAO: TAO (376966|1) - Connection_Handler[8]::handle_input, handle = 8/8 17:56:04.702 [376966] <2> TAO: TAO (376966|1) - Transport[8]::handle_input 17:56:04.702 [376966] <2> TAO: TAO (376966|1) - Transport[8]::process_queue_head 17:56:04.702 [376966] <2> TAO: TAO (376966|1) - Transport[8]::handle_input: read 25 bytes 17:56:04.702 [376966] <2> TAO: TAO (376966|1) - Transport::handle_input(): bytes read from socket - HEXDUMP 25 bytes 47 49 4f 50 01 02 00 01 00 00 00 0d 00 00 00 02 GIOP............ 00 00 00 00 00 00 00 00 00 ......... 17:56:04.702 [376966] <2> TAO: TAO (376966|1) - Transport::handle_input: extracting complete messages 17:56:04.702 [376966] <2> TAO: TAO (376966|1) - from this message buffer - HEXDUMP 25 bytes 47 49 4f 50 01 02 00 01 00 00 00 0d 00 00 00 02 GIOP............ 00 00 00 00 00 00 00 00 00 ......... 17:56:04.702 [376966] <2> TAO: TAO (376966|1) - Queued_Data::make_complete_message: extracted complete message (25 bytes incl hdr) from mblk=ffff3140 into qd=101cf250 17:56:04.702 [376966] <2> TAO: TAO (376966|1) - Transport::handle_input: extracting complete messages 17:56:04.702 [376966] <2> TAO: TAO (376966|1) - from this message buffer - HEXDUMP 0 bytes 17:56:04.702 [376966] <2> TAO: TAO (376966|1) - Transport[8]::process_queue_head 17:56:04.702 [376966] <2> TAO: TAO (376966|1) - Transport[8]::process_queue_head, the size of the queue is [0] 17:56:04.702 [376966] <2> TAO: TAO (376966|1) - Resume_Handle[8]::resume_handle, handle resumed 17:56:04.702 [376966] <2> TAO: TAO (376966|1) - GIOP_Message_Base::dump_msg, recv GIOP v1.2 msg, 13 data bytes, my endian, Type Reply[2] 17:56:04.702 [376966] <2> TAO: TAO (376966|1) - GIOP message - HEXDUMP 25 bytes 47 49 4f 50 01 02 00 01 00 00 00 0d 00 00 00 02 GIOP............ 00 00 00 00 00 00 00 00 00 ......... 17:56:04.702 [376966] <2> TAO: TAO (376966|1)- TAO_Muxed_TMS::dispatch_reply, id = 2 17:56:04.702 [376966] <2> TAO: TAO (376966|1) - Connection_Handler[8]::handle_input, handle = 8/8, retval = 0 17:56:04.702 [376966] <2> TAO: TAO (376966|1) - Leader_Follower[8]::wait_for_event, (leader) exit reactor event loop 17:56:04.702 [376966] <2> TAO: TAO (376966|1) Synch_Invocation::invoke_i, timeout after recv is <1799982> status <1> 17:56:04.702 [376966] <2> TAO: TAO (376966|1) Return value from unbind_dispatcher is [-1] 17:56:04.702 [376966] <2> Orb::connectToObjectOnce: connection created(../Orb.cpp:1052) 17:56:04.702 [376966] <2> Orb::connectToObject: --- EXITING --- : obj = 11011fe10 (../Orb.cpp:1127) 17:56:04.702 [376966] <2> EndpointSelectorFactory::get_selector: +++ ENTERING +++ : obj = 110170b90 (../Endpoint_Selector.cpp:1765) 17:56:04.702 [376966] <2> EndpointSelector_R2::updateConfMembers: +++ ENTERING +++ : obj = 110170c10 (../Endpoint_Selector.cpp:374) 17:56:04.702 [376966] <2> EndpointSelector_R2::updateConfMembers: --- EXITING --- : obj = 110170c10 (../Endpoint_Selector.cpp:374) 17:56:04.702 [376966] <2> EndpointSelectorFactory::get_selector: --- EXITING --- : obj = 110170b90 (../Endpoint_Selector.cpp:1765) 17:56:04.702 [376966] <2> TAO: TAO (376966|1) RT Timeout is <1800000> 17:56:04.702 [376966] <2> EndpointSelector_R2::select_endpoint: +++ ENTERING +++ : obj = 110170c10 (../Endpoint_Selector.cpp:503) 17:56:04.702 [376966] <2> EndpointSelector_R2::select_endpoint: epsr2: EPS Honoring Server List(../Endpoint_Selector.cpp:510) 17:56:04.702 [376966] <2> EndpointSelector_R2::endpoint_from_profile: +++ ENTERING +++ : obj = 110170c10 (../Endpoint_Selector.cpp:579) 17:56:04.702 [376966] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Evaluating 3 endpoints(../Endpoint_Selector.cpp:592) 17:56:04.702 [376966] <2> ConnectList::search: host hydra140 not found in cache(../ConnectList.cpp:116) 17:56:04.702 [376966] <2> ConnectList::search: host 192.168.157.46 found GOOD(../ConnectList.cpp:89) 17:56:04.702 [376966] <2> EndpointSelector_R2::endpoint_from_profile: epsr2: Reusing endpoint, target: 192.168.157.46, source: ANY(../Endpoint_Selector.cpp:629) 17:56:04.702 [376966] <2> VxssContext::updateOnFailure: +++ ENTERING +++ : obj = 110173cb8 (../Endpoint_Selector.cpp:192) 17:56:04.703 [376966] <2> VxssContext::updateOnFailure: --- EXITING --- : obj = 110173cb8 (../Endpoint_Selector.cpp:192) 17:56:04.703 [376966] <2> EndpointSelector_R2::performCallUsingEndpoint: +++ ENTERING +++ : obj = 110170c10 (../Endpoint_Selector.cpp:950) 17:56:04.703 [376966] <2> TAO: TAO (376966|1) Connection Timeout is <0> 17:56:04.703 [376966] <2> TAO: TAO (376966|1) - Transport_Cache_Manager::is_entry_idle_i, state is [0] 17:56:04.703 [376966] <2> TAO: TAO (376966|1) - Transport_Cache_Manager::find_i, index in find <0> (Transport[8]) 17:56:04.703 [376966] <2> TAO: TAO (376966|1) - Transport_Connector::connect, got an existing Transport[8] in role TAO_CLIENT_ROLE 17:56:04.703 [376966] <2> EndpointSelector_R2::performCallUsingEndpoint: epsr2: insecure invocation->perform_call(...) to host 192.168.157.46 SUCCESS(../Endpoint_Selector.cpp:1108) 17:56:04.703 [376966] <2> EndpointSelector_R2::performCallUsingEndpoint: --- EXITING --- : obj = 110170c10 (../Endpoint_Selector.cpp:950) 17:56:04.703 [376966] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1370: 0: found in cache name: 192.168.157.46 17:56:04.703 [376966] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1371: 0: found in cache service: NULL 17:56:04.703 [376966] <2> ConnectList::update: updated host 192.168.157.46 as GOOD, used 0 of 3 retries(../ConnectList.cpp:175) 17:56:04.703 [376966] <2> EndpointSelector_R2::endpoint_from_profile: --- EXITING --- : obj = 110170c10 (../Endpoint_Selector.cpp:579) 17:56:04.703 [376966] <2> EndpointSelector_R2::select_endpoint: --- EXITING --- : obj = 110170c10 (../Endpoint_Selector.cpp:503) 17:56:04.703 [376966] <2> TAO: TAO (376966|1) - Muxed_TMS[8]::request_id, <3> 17:56:04.703 [376966] <2> TAO: TAO (376966|1) - Codeset_Manager::generate_service_context using tcs_c = 00010001, tcs_w = 00010109 17:56:04.703 [376966] <2> TAO: TAO (376966|1) - GIOP_Message_Base::dump_msg, send GIOP v1.2 msg, 139 data bytes, my endian, Type Request[3] 17:56:04.703 [376966] <2> TAO: TAO (376966|1) - GIOP message - HEXDUMP 151 bytes 47 49 4f 50 01 02 00 00 00 00 00 8b 00 00 00 03 GIOP............ 03 00 00 00 00 00 00 00 00 00 00 32 14 01 0f 00 ...........2.... 4e 55 50 00 00 00 16 00 00 00 01 00 00 00 01 00 NUP............. 45 4d 4d 53 65 72 76 65 72 50 4f 41 00 00 00 00 EMMServerPOA.... 00 00 00 00 01 45 4d 4d 53 65 72 76 65 72 45 48 .....EMMServerEH 00 00 00 06 5f 69 73 5f 61 00 00 00 00 00 00 01 ...._is_a....... 00 00 00 01 00 00 00 0c 00 00 00 00 00 01 00 01 ................ 00 01 01 09 00 00 00 00 00 00 00 1b 49 44 4c 3a ............IDL: 56 65 72 69 74 61 73 2f 45 4d 4d 2f 53 65 72 76 Veritas/EMM/Serv 65 72 3a 31 2e 30 00 er:1.0. 17:56:04.703 [376966] <2> TAO: PBXIOP (376966|1) - PBXIOP_Transport::send_message_shared 17:56:04.703 [376966] <2> TAO: TAO (376966|1) - Transport[8]::cleanup_queue, byte_count = 151 17:56:04.703 [376966] <2> TAO: TAO (376966|1) - Transport[8]::cleanup_queue, after transfer, bc = 0, all_sent = 1, ml = 0 17:56:04.703 [376966] <2> TAO: TAO (376966|1) - Transport[8]::drain_queue_helper, byte_count = 151, head_is_empty = 1 17:56:04.703 [376966] <2> TAO: TAO (376966|1) - Transport[8]::drain_queue_i, helper retval = 1 17:56:04.703 [376966] <2> EndpointSelector_R2::success: +++ ENTERING +++ : obj = 110170c10 (../Endpoint_Selector.cpp:557) 17:56:04.703 [376966] <2> EndpointSelector_R2::success: --- EXITING --- : obj = 110170c10 (../Endpoint_Selector.cpp:557) 17:56:04.703 [376966] <2> TAO: TAO (376966|1) - Synch_Invocation::invoke_i, timeout on recv is <1799999> 17:56:04.703 [376966] <2> TAO: TAO (376966|1) - Leader_Follower[8]::wait_for_event, (leader) enter reactor event loop 17:56:04.705 [376966] <2> TAO: TAO (376966|1) - Transport[8]::acts_as_server() opened as TAO_CLIENT_ROLE, bidir_flag=-1, returning 0 17:56:04.705 [376966] <2> TAO: TAO (376966|1) - Connection_Handler[8]::handle_input, handle = 8/8 17:56:04.705 [376966] <2> TAO: TAO (376966|1) - Transport[8]::handle_input 17:56:04.705 [376966] <2> TAO: TAO (376966|1) - Transport[8]::process_queue_head 17:56:04.705 [376966] <2> TAO: TAO (376966|1) - Transport[8]::handle_input: read 25 bytes 17:56:04.705 [376966] <2> TAO: TAO (376966|1) - Transport::handle_input(): bytes read from socket - HEXDUMP 25 bytes 47 49 4f 50 01 02 00 01 00 00 00 0d 00 00 00 03 GIOP............ 00 00 00 00 00 00 00 00 01 ......... 17:56:04.705 [376966] <2> TAO: TAO (376966|1) - Transport::handle_input: extracting complete messages 17:56:04.705 [376966] <2> TAO: TAO (376966|1) - from this message buffer - HEXDUMP 25 bytes 47 49 4f 50 01 02 00 01 00 00 00 0d 00 00 00 03 GIOP............ 00 00 00 00 00 00 00 00 01 ......... 17:56:04.705 [376966] <2> TAO: TAO (376966|1) - Queued_Data::make_complete_message: extracted complete message (25 bytes incl hdr) from mblk=ffff4430 into qd=101cd2f0 17:56:04.705 [376966] <2> TAO: TAO (376966|1) - Transport::handle_input: extracting complete messages 17:56:04.705 [376966] <2> TAO: TAO (376966|1) - from this message buffer - HEXDUMP 0 bytes 17:56:04.705 [376966] <2> TAO: TAO (376966|1) - Transport[8]::process_queue_head 17:56:04.705 [376966] <2> TAO: TAO (376966|1) - Transport[8]::process_queue_head, the size of the queue is [0] 17:56:04.705 [376966] <2> TAO: TAO (376966|1) - Resume_Handle[8]::resume_handle, handle resumed 17:56:04.705 [376966] <2> TAO: TAO (376966|1) - GIOP_Message_Base::dump_msg, recv GIOP v1.2 msg, 13 data bytes, my endian, Type Reply[3] 17:56:04.705 [376966] <2> TAO: TAO (376966|1) - GIOP message - HEXDUMP 25 bytes 47 49 4f 50 01 02 00 01 00 00 00 0d 00 00 00 03 GIOP............ 00 00 00 00 00 00 00 00 01 ......... 17:56:04.705 [376966] <2> TAO: TAO (376966|1)- TAO_Muxed_TMS::dispatch_reply, id = 3 17:56:04.705 [376966] <2> TAO: TAO (376966|1) - Connection_Handler[8]::handle_input, handle = 8/8, retval = 0 17:56:04.705 [376966] <2> TAO: TAO (376966|1) - Leader_Follower[8]::wait_for_event, (leader) exit reactor event loop 17:56:04.705 [376966] <2> TAO: TAO (376966|1) Synch_Invocation::invoke_i, timeout after recv is <1799994> status <1> 17:56:04.705 [376966] <2> TAO: TAO (376966|1) Return value from unbind_dispatcher is [-1] 17:56:04.705 [376966] <4> CreateConnectionID: (-) Using existing uninitialized Connection ID 0 17:56:04.705 [376966] <4> emmlib_initializeEx: (0) Successfully initialized EMM session 17:56:04.705 [376966] <4> get_sts_type: sts host = mini-006 17:56:04.705 [376966] <4> tpconfig:main(): OpenStorage host name "mini-006" specified 17:56:04.705 [376966] <4> tpconfig:main(): OpenStorage type "NEC_HYDRA" specified 17:56:04.706 [376966] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1370: 0: found in cache name: hydra140 17:56:04.706 [376966] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1371: 0: found in cache service: NULL 17:56:04.706 [376966] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1514: 0: found in file cache name: localhost 17:56:04.706 [376966] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1515: 0: found in file cache service: NULL 17:56:04.706 [376966] <2> vnet_same_host: ../../libvlibs/vnet_addrinfo.c.2972: 0: matched as locals: hydra140 17:56:04.706 [376966] <2> vnet_same_host: ../../libvlibs/vnet_addrinfo.c.2973: 0: matched as locals: localhost 17:56:04.706 [376966] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1370: 0: found in cache name: hydra140 17:56:04.706 [376966] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1371: 0: found in cache service: NULL 17:56:04.706 [376966] <2> file_to_addrinfo: ../../libvlibs/vnet_addrinfo.c.6635: 0: fopen() failed: 2 0x00000002 17:56:04.706 [376966] <2> file_to_addrinfo: ../../libvlibs/vnet_addrinfo.c.6636: 0: fopen() failed: /usr/openv/var/host_cache/059/4953659+bprd,1,8,2,1,0+hydra140.txt 17:56:04.709 [376966] <2> retry_getaddrinfo_for_real: ../../libvlibs/vnet_addrinfo.c.1016: 0: getaddrinfo() failed: 8 0x00000008 17:56:04.709 [376966] <2> retry_getaddrinfo_for_real: ../../libvlibs/vnet_addrinfo.c.1018: 0: getaddrinfo() failed name: NULL 17:56:04.709 [376966] <2> retry_getaddrinfo_for_real: ../../libvlibs/vnet_addrinfo.c.1020: 0: getaddrinfo() failed service: bprd 17:56:04.709 [376966] <2> retry_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.868: 0: using IANA service: bprd 17:56:04.709 [376966] <2> retry_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.869: 0: using IANA port: 13720 0x00003598 17:56:04.709 [376966] <2> retry_getaddrinfo: ../../libvlibs/vnet_addrinfo.c.896: 0: adjusted service name: 13720 17:56:04.709 [376966] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1572: 0: found via getaddrinfo name: hydra140 17:56:04.709 [376966] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1574: 0: found via getaddrinfo service: bprd 17:56:04.722 [376966] <2> vnet_sortaddrs: ../../libvlibs/vnet_addrinfo.c.4026: 0: sorted addrs:: 2 0x00000002 17:56:04.722 [376966] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1370: 0: found in cache name: 192.168.157.46 17:56:04.722 [376966] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1371: 0: found in cache service: NULL 17:56:04.722 [376966] <2> get_pref_netconnection: ../../libvlibs/vnet_addrinfo.c.4849: 0: Local [strong] check, using interface : ANY 17:56:04.722 [376966] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1370: 0: found in cache name: 192.168.157.46 17:56:04.722 [376966] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1371: 0: found in cache service: NULL 17:56:04.722 [376966] <2> get_pref_netconnection: ../../libvlibs/vnet_addrinfo.c.4849: 0: Local [strong] check, using interface : ANY 17:56:04.724 [376966] <2> vnet_async_connect: ../../libvlibs/vnet_connect.c.1157: 0: connect: immediate CONNECT FROM 192.168.157.46.49680 TO 192.168.157.46.13720 fd = 9 17:56:04.724 [376966] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1370: 0: found in cache name: hydra140 17:56:04.725 [376966] <2> vnet_cached_getaddrinfo_and_update: ../../libvlibs/vnet_addrinfo.c.1371: 0: found in cache service: NULL 17:56:04.725 [376966] <2> logconnections: BPRD CONNECT FROM 192.168.157.46.49680 TO 192.168.157.46.13720 fd = 9 17:56:04.725 [376966] <2> vnet_check_vxss_client_magic_with_info: ../../libvlibs/vnet_vxss_helper.c.871: 0: Ignoring VxSS authentication: 2 0x00000002 17:56:04.727 [376966] <4> CheckMasterServerLicenses: snapshot_client: bfe_licensed = 1, bfe_value = 0; advanced_disk: bfe_licensed = 1, bfe_value = 0; open_storage: bfe_licensed = 1, bfe_value = 0; pure disk: bfe_licensed = 1, bfe_value = 0 17:56:04.727 [376966] <2> hydra140: libsts claim_server_dir() 12/09/12 17:56:04: opening plugin directory /usr/openv/lib 17:56:04.727 [376966] <2> hydra140: libsts openp() 12/09/12 17:56:04: opening module /usr/openv/lib/libstspibasicdisk.so 17:56:04.728 [376966] <2> hydra140: libsts openp() 12/09/12 17:56:04: opening module /usr/openv/lib/libstspinbostpxy.so 17:56:04.729 [376966] <2> hydra140: libsts openp() 12/09/12 17:56:04: opening module /usr/openv/lib/libstspinearstore.so 17:56:04.731 [376966] <2> hydra140: libsts openp() 12/09/12 17:56:04: opening module /usr/openv/lib/libstspicp.so 17:56:04.731 [376966] <2> hydra140: libsts openp() 12/09/12 17:56:04: opening module /usr/openv/lib/libstspishmem.so 17:56:04.732 [376966] <2> hydra140: libsts claim_server_dir() 12/09/12 17:56:04: opening plugin directory /usr/openv/lib/ost-plugins 17:56:04.732 [376966] <2> hydra140: libsts openp() 12/09/12 17:56:04: opening module /usr/openv/lib/ost-plugins/libstspihydraOst.so 17:56:05.513 [376966] <2> hydra140: libsts check_replace_logical_with_physical_touchfile() 12/09/12 17:56:05: NOT FOUND: file '/usr/openv/lib/REPLACE_LOGICAL_WITH_PHYSICAL_CAPACITY.NEC_HYDRA' 17:56:05.513 [376966] <2> hydra140: libsts check_replace_logical_with_physical_touchfile() 12/09/12 17:56:05: NOT FOUND: file '/usr/openv/lib/ost-plugins/REPLACE_LOGICAL_WITH_PHYSICAL_CAPACITY.NEC_HYDRA'