[tao-bugs] Service after some time working idle (1 night) can't process requests
Daniel Suchodolski
Daniel.Suchodolski at pitradwar.com
Thu Jan 12 07:32:01 CST 2017
Hi TAO,
TAO VERSION: 2.4.1
ACE VERSION: 6.4.1
HOST MACHINE and OPERATING SYSTEM:
Debian 8 (Jessie)
COMPILER: g++ (Debian 4.9.2-10) 4.9.2
DOES THE PROBLEM AFFECT:
EXECUTION? YES
SYNOPSIS:
CORBA service after some time working idle (1 night) can't process requests. (migration result)
DESCRIPTION:
At the beginning I want to highlight that we use version of ACE-TAO compiled with option "threads 0". The problem started to happen with many services after migration to newest version of ACE TAO and operating system. Up to now the system worked stable on version 1.2.1/5.2.1 (linux lenny).
How it works:
Server application registers CORBA service in NamingService.
If a client connects shortly after start of the server
then the server works fine: the server process request properly.
After some time (for example 1 night) a client connects to the Server, but
when the client tries to use service then the server hangs up and use 100% of a processor.
Client is blocked by the server until the server is killed. Debugging, we found out that the problem is somewhere inside CORBA invocation. Very unclear are debug information seen during loading adatp3-services.svc, but we are not able fully interpret this issue.
The services is run with the following ORBParameters:
-ORBDottedDecimalAddresses 1
-ORBDebug -ORBDebugLevel 10 -ORBVerboseLogging 2 -ORBInitRef NameService=corbaloc::server:30033/NameService
-ORBSvcConf adatp3-services.svc
and adatp3-services.svc:
static Advanced_Resource_Factory "-ORBReactorMaskSignals 0 -ORBInputCDRAllocator null -ORBReactorType select_st -ORBConnec
tionCacheLock null"
static Server_Strategy_Factory "-ORBAllowReactivationOfSystemids 0"
static Client_Strategy_Factory "-ORBTransportMuxStrategy EXCLUSIVE -ORBClientConnectionHandler RW"
REPEAT BY:
Every Time
TAO LOG:
The Log of the server is showed below. The log is divided into parts:
[Start Server]
[Client connect to Server after short time]
[Client is connecting after some time]
[Start Server]
Starting: adatp3-services
ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=TAO_Codeset type=0x1db15b0 => 0x1db1560
ACE (23677|1) SG::process_directive, repo=0x1dab0b0 - remove TAO_Codeset
ACE (23677|1) SG::process_directives_i, repo=0x1dab0b0 - remove TAO_Codeset
ACE (23677|1) - SCG:<ctor=0x7ffe091c35f0> - config=0x1dab040 repo=0x1dab0b0 superceded by repo=0x1dab0b0
ACE (23677|1) SR::remove_i - repo=0x1dab0b0 [0], name=TAO_Codeset (removed) (type=0x1db1610, active=0)
ACE (23677|1) ST::fini - destroying name=TAO_Codeset, dll=(null)
ACE (23677|1) Remove_Node::apply - did remove on TAO_Codeset, error = 0
ACE (23677|1) SCG:<dtor=0x7ffe091c35f0> - new repo=0x1dab0b0
ACE (23677|1) SG::process_directive, repo=0x1dab0b0 - dynamic TAO_Codeset Service_Object * TAO_Codeset:_make_TAO_Codeset_Manager_Factory() ""
ACE (23677|1) SG::process_directives_i, repo=0x1dab0b0 - dynamic TAO_Codeset Service_Object * TAO_Codeset:_make_TAO_Codeset_Manager_Factory() ""
ACE (23677|1) - SCG:<ctor=0x7ffe091c35f0> - config=0x1dab040 repo=0x1dab0b0 superceded by repo=0x1dab0b0
ACE (23677|1) SG::initialize - repo=0x1dab0b0, name=TAO_Codeset - looking up in the repo
ACE (23677|1) STDG::<ctor>, repo=0x1dab0b0, name=TAO_Codeset - beginning at [40]
ACE (23677|1) LN::open_dll - path=TAO_Codeset
ACE (23677|1) SG::process_directive_i, repo=0x1dab0b0 - UTF8_Latin1_Factory, dll=<null>, force=0
ACE (23677|1) SR::insert - repo=0x1dab0b0 [40], name=UTF8_Latin1_Factory (new) (type=0x1dc9af0, object=0x1dc8c28, active=0)
ACE (23677|1) SG::add_processed_static_svc, repo=0x1dab0b0 - UTF8_Latin1_Factory
ACE (23677|1) SG::process_directive_i, repo=0x1dab0b0 - UTF16_BOM_Factory, dll=<null>, force=0
ACE (23677|1) SR::insert - repo=0x1dab0b0 [41], name=UTF16_BOM_Factory (new) (type=0x1dc9ca0, object=0x1dc7b88, active=0)
ACE (23677|1) SG::add_processed_static_svc, repo=0x1dab0b0 - UTF16_BOM_Factory
ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=TAO_Codeset type=(nil) => (nil)
ACE (23677|1) SG::process_directive_i, repo=0x1dab0b0 - TAO_Codeset, dll=<null>, force=1
ACE (23677|1) SR::insert - repo=0x1dab0b0 [42], name=TAO_Codeset (new) (type=0x1dca3c0, object=0x1dca160, active=0)
ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=TAO_Codeset type=0x1dca3c0 => 0x1dca160
ACE (23677|1) DLL_Handle::open ("libTAO_Codeset.so", 0x101) -> succeeded: no error
ACE (23677|1) DLL_Handle::open - TAO_Codeset (31260576), refcount=1
ACE (23677|1) DLL_Handle::open - TAO_Codeset (31260576), refcount=2
ACE (23677|1) SR::insert - repo=0x1dab0b0 [42], name=TAO_Codeset (replacing) (type=0x1dcab50, object=0x1dcabe0, active=1)
ACE (23677|1) ST::fini - destroying name=TAO_Codeset, dll=(null)
ACE (23677|1) STDG::<dtor>, repo=0x1dab0b0 [42], name=TAO_Codeset - updating dependents [40 - 43)
ACE (23677|1) DLL_Handle::get_handle: post call: handle valid, refcount 2
ACE (23677|1) SR::relocate_i - repo=0x1dab0b0 [40]: trying name=UTF8_Latin1_Factory, handle: 0 -> 31260576
ACE (23677|1) SR::relocate_i - repo=0x1dab0b0 [40]: relocating name=UTF8_Latin1_Factory, handle: 0 -> 31260576
ACE (23677|1) DLL_Handle::open - TAO_Codeset (31260576), refcount=3
ACE (23677|1) SR::relocate_i - repo=0x1dab0b0 [41]: trying name=UTF16_BOM_Factory, handle: 0 -> 31260576
ACE (23677|1) SR::relocate_i - repo=0x1dab0b0 [41]: relocating name=UTF16_BOM_Factory, handle: 0 -> 31260576
ACE (23677|1) DLL_Handle::open - TAO_Codeset (31260576), refcount=4
ACE (23677|1) DLL_Handle::get_handle: post call: handle valid, refcount 4
ACE (23677|1) SR::relocate_i - repo=0x1dab0b0 [42]: trying name=TAO_Codeset, handle: 31260576 -> 31260576
ACE (23677|1) STDG::<dtor>, repo=0x1dab0b0 [42], name=TAO_Codeset - loaded (type=0x1dc6500, impl=0x1dcab50, object=0x1dcabe0, active=1)
ACE (23677|1) Dynamic_Node::apply - Did dynamic on TAO_Codeset (yyerrno=0)
ACE (23677|1) DLL_Handle::close - TAO_Codeset (handle=31260576, refcount=3)
ACE (23677|1) SCG:<dtor=0x7ffe091c35f0> - new repo=0x1dab0b0
ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=TAO_Codeset type=0x1dcab50 => 0x1dcabe0
ACE (23677|1) SG::process_directive_i, repo=0x1dab0b0 - Resource_Factory, dll=<null>, force=0
ACE (23677|1) SR::insert - repo=0x1dab0b0 [43], name=Resource_Factory (new) (type=0x1dca050, object=0x1dc8110, active=0)
ACE (23677|1) SG::add_processed_static_svc, repo=0x1dab0b0 - Resource_Factory
ACE (23677|1) SG::process_directive_i, repo=0x1dab0b0 - Client_Strategy_Factory, dll=<null>, force=0
ACE (23677|1) SR::insert - repo=0x1dab0b0 [44], name=Client_Strategy_Factory (new) (type=0x1dca450, object=0x1dc6120, active=0)
ACE (23677|1) SG::add_processed_static_svc, repo=0x1dab0b0 - Client_Strategy_Factory
ACE (23677|1) SG::process_directive_i, repo=0x1dab0b0 - Server_Strategy_Factory, dll=<null>, force=0
ACE (23677|1) SR::insert - repo=0x1dab0b0 [45], name=Server_Strategy_Factory (new) (type=0x1dd46a0, object=0x1dc6350, active=0)
ACE (23677|1) SG::add_processed_static_svc, repo=0x1dab0b0 - Server_Strategy_Factory
ACE (23677|1) SG::process_directive_i, repo=0x1dab0b0 - IIOP_Factory, dll=<null>, force=0
ACE (23677|1) SR::insert - repo=0x1dab0b0 [46], name=IIOP_Factory (new) (type=0x1dcdf50, object=0x1dce390, active=0)
ACE (23677|1) SG::add_processed_static_svc, repo=0x1dab0b0 - IIOP_Factory
ACE (23677|1) SG::process_directive_i, repo=0x1dab0b0 - MCAST_Parser, dll=<null>, force=0
ACE (23677|1) SR::insert - repo=0x1dab0b0 [47], name=MCAST_Parser (new) (type=0x1dca800, object=0x1dc8cd0, active=0)
ACE (23677|1) SG::add_processed_static_svc, repo=0x1dab0b0 - MCAST_Parser
ACE (23677|1) SG::process_directive_i, repo=0x1dab0b0 - CORBANAME_Parser, dll=<null>, force=0
ACE (23677|1) SR::insert - repo=0x1dab0b0 [48], name=CORBANAME_Parser (new) (type=0x1dce320, object=0x1dce170, active=0)
ACE (23677|1) SG::add_processed_static_svc, repo=0x1dab0b0 - CORBANAME_Parser
ACE (23677|1) SG::process_directive_i, repo=0x1dab0b0 - CORBALOC_Parser, dll=<null>, force=0
ACE (23677|1) SR::insert - repo=0x1dab0b0 [49], name=CORBALOC_Parser (new) (type=0x1dcb7b0, object=0x1dd0470, active=0)
ACE (23677|1) SG::add_processed_static_svc, repo=0x1dab0b0 - CORBALOC_Parser
ACE (23677|1) SG::process_directive_i, repo=0x1dab0b0 - FILE_Parser, dll=<null>, force=0
ACE (23677|1) SR::insert - repo=0x1dab0b0 [50], name=FILE_Parser (new) (type=0x1dc8b30, object=0x1dc8ae0, active=0)
ACE (23677|1) SG::add_processed_static_svc, repo=0x1dab0b0 - FILE_Parser
ACE (23677|1) SG::process_directive_i, repo=0x1dab0b0 - DLL_Parser, dll=<null>, force=0
ACE (23677|1) SR::insert - repo=0x1dab0b0 [51], name=DLL_Parser (new) (type=0x1dcca90, object=0x1dcca40, active=0)
ACE (23677|1) SG::add_processed_static_svc, repo=0x1dab0b0 - DLL_Parser
ACE (23677|1) SG::process_directive_i, repo=0x1dab0b0 - HTTP_Parser, dll=<null>, force=0
ACE (23677|1) SR::insert - repo=0x1dab0b0 [52], name=HTTP_Parser (new) (type=0x1dc4920, object=0x1dcaa30, active=0)
ACE (23677|1) SG::add_processed_static_svc, repo=0x1dab0b0 - HTTP_Parser
ACE (23677|1) SG::process_directive_i, repo=0x1dab0b0 - Default_Stub_Factory, dll=<null>, force=0
ACE (23677|1) SR::insert - repo=0x1dab0b0 [53], name=Default_Stub_Factory (new) (type=0x1dc49a0, object=0x1dc7c30, active=0)
ACE (23677|1) SG::add_processed_static_svc, repo=0x1dab0b0 - Default_Stub_Factory
ACE (23677|1) SG::process_directive_i, repo=0x1dab0b0 - Default_Endpoint_Selector_Factory, dll=<null>, force=0
ACE (23677|1) SR::insert - repo=0x1dab0b0 [54], name=Default_Endpoint_Selector_Factory (new) (type=0x1dc7cc0, object=0x1dc5970, active=0)
ACE (23677|1) SG::add_processed_static_svc, repo=0x1dab0b0 - Default_Endpoint_Selector_Factory
ACE (23677|1) SG::process_directive_i, repo=0x1dab0b0 - Default_Thread_Lane_Resources_Manager_Factory, dll=<null>, force=0
ACE (23677|1) SR::insert - repo=0x1dab0b0 [55], name=Default_Thread_Lane_Resources_Manager_Factory (new) (type=0x1dc7ec0, object=0x1dc7e70, active=0)
ACE (23677|1) SG::add_processed_static_svc, repo=0x1dab0b0 - Default_Thread_Lane_Resources_Manager_Factory
ACE (23677|1) SG::process_directive_i, repo=0x1dab0b0 - Default_Collocation_Resolver, dll=<null>, force=0
ACE (23677|1) SR::insert - repo=0x1dab0b0 [56], name=Default_Collocation_Resolver (new) (type=0x1dc62d0, object=0x1dcb8b0, active=0)
ACE (23677|1) SG::add_processed_static_svc, repo=0x1dab0b0 - Default_Collocation_Resolver
ACE (23677|1) SG::process_directive_i, repo=0x1dab0b0 - Time_Policy_Manager, dll=<null>, force=0
ACE (23677|1) SR::insert - repo=0x1dab0b0 [57], name=Time_Policy_Manager (new) (type=0x1db6c90, object=0x1dc7fe0, active=0)
ACE (23677|1) SG::add_processed_static_svc, repo=0x1dab0b0 - Time_Policy_Manager
ACE (23677|1) SG::process_directive_i, repo=0x1dab0b0 - TAO_SYSTEM_TIME_POLICY, dll=<null>, force=0
ACE (23677|1) SR::insert - repo=0x1dab0b0 [58], name=TAO_SYSTEM_TIME_POLICY (new) (type=0x1dcadb0, object=0x1dcad60, active=0)
ACE (23677|1) SG::add_processed_static_svc, repo=0x1dab0b0 - TAO_SYSTEM_TIME_POLICY
ACE (23677|1) SG::process_directive_i, repo=0x1dab0b0 - TAO_HR_TIME_POLICY, dll=<null>, force=0
ACE (23677|1) SR::insert - repo=0x1dab0b0 [59], name=TAO_HR_TIME_POLICY (new) (type=0x1dc9f70, object=0x1dc9f20, active=0)
ACE (23677|1) SG::add_processed_static_svc, repo=0x1dab0b0 - TAO_HR_TIME_POLICY
ACE (23677|1) SG::init_svc_conf_file_queue - this=0x1dab040, repo=0x1dab0b0
ACE (23677|1) SG::open_i - this=0x1dab040, opened=0, loadstatics=0
ACE (23677|1) SG::init_svc_conf_file_queue - this=0x1dab040, repo=0x1dab0b0
ACE (23677|1) SG::process_directive_i, repo=0x1dab0b0 - ACE_Naming_Context, dll=<null>, force=1
ACE (23677|1) SR::insert - repo=0x1dab0b0 [60], name=ACE_Naming_Context (new) (type=0x1dc6d00, object=0x1dc6c00, active=0)
ACE (23677|1) SG::add_processed_static_svc, repo=0x1dab0b0 - ACE_Naming_Context
ACE (23677|1) SG::process_directive_i, repo=0x1dab0b0 - ACE_Service_Manager, dll=<null>, force=1
ACE (23677|1) SR::insert - repo=0x1dab0b0 [61], name=ACE_Service_Manager (new) (type=0x1db1620, object=0x1db15c0, active=0)
ACE (23677|1) SG::add_processed_static_svc, repo=0x1dab0b0 - ACE_Service_Manager
ACE (23677|1) STDG::<ctor>, repo=0x1dab0b0, name=adatp3-services.svc - beginning at [62]
ACE (23677|1) SG::process_directives_i, repo=0x1dab0b0 - <from file>
ACE (23677|1) - SCG:<ctor=0x7ffe091c34d0> - config=0x1dab040 repo=0x1dab0b0 superceded by repo=0x1dab0b0
ACE (23677|1) SG::initialize - () repo=0x1dab0b0, looking up static service 'Advanced_Resource_Factory' to initialize
ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=Resource_Factory type=0x1dca050 => 0x1dc8110
ACE (23677|1) Static_Node::apply - Did static on Advanced_Resource_Factory (yyerrno=0)
ACE (23677|1) SG::initialize - () repo=0x1dab0b0, looking up static service 'Server_Strategy_Factory' to initialize
ACE (23677|1) Static_Node::apply - Did static on Server_Strategy_Factory (yyerrno=0)
ACE (23677|1) SG::initialize - () repo=0x1dab0b0, looking up static service 'Client_Strategy_Factory' to initialize
ACE (23677|1) Static_Node::apply - Did static on Client_Strategy_Factory (yyerrno=0)
ACE (23677|1) SCG:<dtor=0x7ffe091c34d0> - new repo=0x1dab0b0
ACE (23677|1) STDG::<dtor> - Failed (-1) to find adatp3-services.svc -> (nil)
TAO (23677|1) - Completed initializing the process-wide service context
TAO (23677|1) - Default ORB services initialization begins
ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=PI_Server_Loader type=(nil) => (nil)
ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=BiDirGIOP_Loader type=(nil) => (nil)
ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=ZIOP_Loader type=(nil) => (nil)
ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=Messaging_Loader type=(nil) => (nil)
ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=RT_ORB_Loader type=(nil) => (nil)
ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=RTScheduler_Loader type=(nil) => (nil)
ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=CSD_Framework_Loader type=(nil) => (nil)
ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=EndpointPolicy_Initializer type=(nil) => (nil)
ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=DiffservPolicy_Initializer type=(nil) => (nil)
TAO (23677|1) - Default ORB services initialization completed
ACE (23677|1) SCG:<dtor=0x7ffe091c3710> - new repo=0x1dab0b0
ACE (23677|1) - SCG:<ctor=0x7ffe091c38e0> - config=0x1dab040 repo=0x1dab0b0 superceded by repo=0x1dab0b0
TAO (23677|1) - We are the default ORB ...
TAO (23677|1) - Initializing the orb-specific services
ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=ORBInitializer_Registry type=(nil) => (nil)
ACE (23677|1) SG::process_directive, repo=0x1dab0b0 - dynamic ORBInitializer_Registry Service_Object * TAO_PI:_make_ORBInitializer_Registry() ""
ACE (23677|1) SG::process_directives_i, repo=0x1dab0b0 - dynamic ORBInitializer_Registry Service_Object * TAO_PI:_make_ORBInitializer_Registry() ""
ACE (23677|1) - SCG:<ctor=0x7ffe091c37b0> - config=0x1dab040 repo=0x1dab0b0 superceded by repo=0x1dab0b0
ACE (23677|1) SG::initialize - repo=0x1dab0b0, name=ORBInitializer_Registry - looking up in the repo
ACE (23677|1) STDG::<ctor>, repo=0x1dab0b0, name=ORBInitializer_Registry - beginning at [62]
ACE (23677|1) LN::open_dll - path=TAO_PI
ACE (23677|1) DLL_Handle::open ("libTAO_PI.so", 0x101) -> succeeded: no error
ACE (23677|1) DLL_Handle::open - TAO_PI (76058624), refcount=1
ACE (23677|1) DLL_Handle::open - TAO_PI (76058624), refcount=2
ACE (23677|1) SG::process_directive_i, repo=0x1dab0b0 - ClientRequestInterceptor_Adapter_Factory, dll=<null>, force=0
ACE (23677|1) SR::insert - repo=0x1dab0b0 [62], name=ClientRequestInterceptor_Adapter_Factory (new) (type=0x1dc7160, object=0x1dc7110, active=0)
ACE (23677|1) SG::add_processed_static_svc, repo=0x1dab0b0 - ClientRequestInterceptor_Adapter_Factory
ACE (23677|1) SG::process_directive_i, repo=0x1dab0b0 - PICurrent_Loader, dll=<null>, force=0
ACE (23677|1) SR::insert - repo=0x1dab0b0 [63], name=PICurrent_Loader (new) (type=0x1dc73a0, object=0x1dc7350, active=0)
ACE (23677|1) SG::add_processed_static_svc, repo=0x1dab0b0 - PICurrent_Loader
TAO (23677|1) - ORBInitializer_Registry::register_orb_initializer 0 @0x1dd2850
ACE (23677|1) SR::insert - repo=0x1dab0b0 [64], name=ORBInitializer_Registry (new) (type=0x1dc5320, object=0x1dd4430, active=1)
ACE (23677|1) STDG::<dtor>, repo=0x1dab0b0 [64], name=ORBInitializer_Registry - updating dependents [62 - 65)
ACE (23677|1) DLL_Handle::get_handle: post call: handle valid, refcount 2
ACE (23677|1) SR::relocate_i - repo=0x1dab0b0 [62]: trying name=ClientRequestInterceptor_Adapter_Factory, handle: 0 -> 76058624
ACE (23677|1) SR::relocate_i - repo=0x1dab0b0 [62]: relocating name=ClientRequestInterceptor_Adapter_Factory, handle: 0 -> 76058624
ACE (23677|1) DLL_Handle::open - TAO_PI (76058624), refcount=3
ACE (23677|1) SR::relocate_i - repo=0x1dab0b0 [63]: trying name=PICurrent_Loader, handle: 0 -> 76058624
ACE (23677|1) SR::relocate_i - repo=0x1dab0b0 [63]: relocating name=PICurrent_Loader, handle: 0 -> 76058624
ACE (23677|1) DLL_Handle::open - TAO_PI (76058624), refcount=4
ACE (23677|1) DLL_Handle::get_handle: post call: handle valid, refcount 4
ACE (23677|1) SR::relocate_i - repo=0x1dab0b0 [64]: trying name=ORBInitializer_Registry, handle: 76058624 -> 76058624
ACE (23677|1) STDG::<dtor>, repo=0x1dab0b0 [64], name=ORBInitializer_Registry - loaded (type=0x1dcfe70, impl=0x1dc5320, object=0x1dd4430, active=1)
ACE (23677|1) Dynamic_Node::apply - Did dynamic on ORBInitializer_Registry (yyerrno=0)
ACE (23677|1) DLL_Handle::close - TAO_PI (handle=76058624, refcount=3)
ACE (23677|1) SCG:<dtor=0x7ffe091c37b0> - new repo=0x1dab0b0
ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=ORBInitializer_Registry type=0x1dc5320 => 0x1dd4430
2017-01-11 11:34:51.105@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=TAO_ORB_Core_Static_Resources type=0x1db1a30 => 0x1db17a0
2017-01-11 11:34:51.105@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=Advanced_Resource_Factory type=0x1db8db0 => 0x1db7280
2017-01-11 11:34:51.105@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=DLL_Parser type=0x1dcca90 => 0x1dcca40
2017-01-11 11:34:51.105@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=FILE_Parser type=0x1dc8b30 => 0x1dc8ae0
2017-01-11 11:34:51.105@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=CORBALOC_Parser type=0x1dcb7b0 => 0x1dd0470
2017-01-11 11:34:51.105@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=CORBANAME_Parser type=0x1dce320 => 0x1dce170
2017-01-11 11:34:51.105@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=MCAST_Parser type=0x1dca800 => 0x1dc8cd0
2017-01-11 11:34:51.105@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=HTTP_Parser type=0x1dc4920 => 0x1dcaa30
2017-01-11 11:34:51.105@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=Default_Thread_Lane_Resources_Manager_Factory type=0x1dc7ec0 => 0x1dc7e70
2017-01-11 11:34:51.106@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=Time_Policy_Manager type=0x1db6c90 => 0x1dc7fe0
2017-01-11 11:34:51.106@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=TAO_SYSTEM_TIME_POLICY type=0x1dcadb0 => 0x1dcad60
2017-01-11 11:34:51.106@<local_host>@23677 at LM_INFO@ADatP3Services|TAO (23677|1) - TAO_Time_Policy_Manager: loaded time policy strategy 'TAO_SYSTEM_TIME_POLICY'
2017-01-11 11:34:51.107@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=Server_Strategy_Factory type=0x1dd46a0 => 0x1dc6350
2017-01-11 11:34:51.107@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=TAO_Codeset type=0x1dcab50 => 0x1dcabe0
2017-01-11 11:34:51.107@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Default_Resource_Factory - codeset manager=0x1dc5e30
2017-01-11 11:34:51.107@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=UTF8_Latin1_Factory type=0x1dc9af0 => 0x1dc8c28
2017-01-11 11:34:51.107@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Codeset_Manager_i::init_ccs, Loaded Codeset translator <UTF8_Latin1_Factory>, ncs = 00010001 tcs = 05010001
2017-01-11 11:34:51.107@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=UTF16_BOM_Factory type=0x1dc9ca0 => 0x1dc7b88
2017-01-11 11:34:51.107@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - UTF16_BOM_Translator: forceBE 0
2017-01-11 11:34:51.107@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=Resource_Factory type=0x1dca050 => 0x1dc8110
2017-01-11 11:34:51.107@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=IIOP_Factory type=0x1dcdf50 => 0x1dce390
2017-01-11 11:34:51.107@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Loaded default protocol <IIOP_Factory>
2017-01-11 11:34:51.107@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=UIOP_Factory type=0x1dbd940 => 0x1dbd9f0
2017-01-11 11:34:51.107@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Loaded default protocol <UIOP_Factory>
2017-01-11 11:34:51.107@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=SHMIOP_Factory type=0x1dbd7d0 => 0x1db8ec0
2017-01-11 11:34:51.107@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Loaded default protocol <SHMIOP_Factory>
2017-01-11 11:34:51.107@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=DIOP_Factory type=0x1dbdc10 => 0x1dbdcc0
2017-01-11 11:34:51.107@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Loaded default protocol <DIOP_Factory>
2017-01-11 11:34:51.107@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=Client_Strategy_Factory type=0x1dca450 => 0x1dc6120
2017-01-11 11:34:51.107@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=Protocols_Hooks type=(nil) => (nil)
2017-01-11 11:34:51.107@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=TAO_Object_Adapter_Factory type=0x1db8b40 => 0x1db6d90
2017-01-11 11:34:51.107@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=TAO_ORB_Core_Static_Resources type=0x1db1a30 => 0x1db17a0
2017-01-11 11:34:51.107@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=Network_Priority_Protocols_Hooks type=(nil) => (nil)
2017-01-11 11:34:51.107@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=FT_ClientService_Activate type=(nil) => (nil)
2017-01-11 11:34:51.107@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=PolicyFactory_Loader type=0x1db46b0 => 0x1db4660
2017-01-11 11:34:51.107@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Created new ORB <>
2017-01-11 11:34:51.107@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) SCG:<dtor=0x7ffe091c38e0> - new repo=0x1dab0b0
2017-01-11 11:34:51.107@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) - SCG:<ctor=0x7ffe091c3880> - config=0x1dab040 repo=0x1dab0b0 superceded by repo=0x1dab0b0
2017-01-11 11:34:51.107@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=TAO_Object_Adapter_Factory type=0x1db8b40 => 0x1db6d90
2017-01-11 11:34:51.107@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=BiDirGIOP_Loader type=(nil) => (nil)
2017-01-11 11:34:51.107@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - IIOP_Acceptor::open_i, listening on: <10.50.31.16:44127>
2017-01-11 11:34:51.107@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - IIOP_Acceptor::open_i, listening on: <10.50.31.100:44127>
2017-01-11 11:34:51.107@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=BiDirGIOP_Loader type=(nil) => (nil)
2017-01-11 11:34:51.108@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=TAO_Acceptor_Filter_Factory type=0x1dbb830 => 0x1dbb240
2017-01-11 11:34:51.108@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=TAO_Network_Priority_Hook type=0x1dbcc10 => 0x1dbc830
2017-01-11 11:34:51.108@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=ORT_Adapter_Factory type=(nil) => (nil)
2017-01-11 11:34:51.108@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=ThreadStrategyFactory type=0x1dbbf80 => 0x1dbb9d0
2017-01-11 11:34:51.108@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=ThreadStrategyORBControl type=0x1db8d70 => 0x1db6ff0
2017-01-11 11:34:51.108@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=IdAssignmentStrategyFactory type=0x1dbb6b0 => 0x1dbaef0
2017-01-11 11:34:51.108@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=IdAssignmentStrategySystem type=0x1dbd6b0 => 0x1dbd290
2017-01-11 11:34:51.108@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=IdUniquenessStrategyFactory type=0x1dbb4a0 => 0x1db9af0
2017-01-11 11:34:51.108@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=IdUniquenessStrategyUniqueFactory type=0x1dbca90 => 0x1dbc710
2017-01-11 11:34:51.108@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=ServantRetentionStrategyFactory type=0x1db9900 => 0x1db6f00
2017-01-11 11:34:51.108@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=ServantRetentionStrategyRetainFactory type=0x1db9530 => 0x1db7230
2017-01-11 11:34:51.108@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=RequestProcessingStrategyFactory type=0x1dbae60 => 0x1db92f0
2017-01-11 11:34:51.108@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=RequestProcessingStrategyAOMOnlyFactory type=0x1dbac50 => 0x1db7b00
2017-01-11 11:34:51.108@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=LifespanStrategyFactory type=0x1dbc250 => 0x1dbb710
2017-01-11 11:34:51.108@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=LifespanStrategyTransientFactory type=0x1dbc130 => 0x1dba7d0
2017-01-11 11:34:51.108@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=ImplicitActivationStrategyFactory type=0x1dbb390 => 0x1db97d0
2017-01-11 11:34:51.108@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=ImplicitActivationStrategyImplicit type=0x1dbc6b0 => 0x1dbbfe0
2017-01-11 11:34:51.108@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=TAO_ORB_Core_Static_Resources type=0x1db1a30 => 0x1db17a0
2017-01-11 11:34:51.108@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=IORInterceptor_Adapter_Factory type=(nil) => (nil)
2017-01-11 11:34:51.108@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) SCG:<dtor=0x7ffe091c3880> - new repo=0x1dab0b0
2017-01-11 11:34:51.108@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=TAO_ORB_Core_Static_Resources type=0x1db1a30 => 0x1db17a0
2017-01-11 11:34:51.108@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=IORInterceptor_Adapter_Factory type=(nil) => (nil)
2017-01-11 11:34:51.109@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=Default_Stub_Factory type=0x1dc49a0 => 0x1dc7c30
2017-01-11 11:34:51.109@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Stub::base_profiles, acquired profile lock this = 0x1dd2200
2017-01-11 11:34:51.109@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) - SCG:<ctor=0x7ffe091c3670> - config=0x1dab040 repo=0x1dab0b0 superceded by repo=0x1dab0b0
2017-01-11 11:34:51.109@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Invocation_Adapter::invoke_i, making a TAO_CS_REMOTE_STRATEGY invocation
2017-01-11 11:34:51.109@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=Default_Endpoint_Selector_Factory type=0x1dc7cc0 => 0x1dc5970
2017-01-11 11:34:51.109@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=TAO_ORB_Core_Static_Resources type=0x1db1a30 => 0x1db17a0
2017-01-11 11:34:51.109@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport_Cache_Manager_T::fill_set_i, current_size = 0, cache_maximum = 32768
2017-01-11 11:34:51.109@<local_host>@23677 at LM_INFO@ADatP3Services|TAO (23677|1) - Transport_Cache_Manager_T::purge, Cache size after purging is [0]
2017-01-11 11:34:51.109@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - IIOP_Connector::begin_connection, to <master:30033> which should block
2017-01-11 11:34:51.109@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - TAO_LF_CH_Event[0]::state_changed_i, state LFS_IDLE->LFS_CONNECTION_WAIT
2017-01-11 11:34:51.109@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - IIOP_Connection_Handler[31350240]::IIOP_Connection_Handler, this=0x1de5cd0
2017-01-11 11:34:51.109@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport_Connector::wait_for_connection_completion, transport [31350240], Connection not complete.
2017-01-11 11:34:51.109@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Cache_IntId_T::Cache_IntId_T, this=0x7ffe091c3120 Transport[31350240] is not connected
2017-01-11 11:34:51.109@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Cache_IntId_T::recycle_state, ENTRY_UNKNOWN->ENTRY_CONNECTING Transport[31350240] IntId=0x7ffe091c3120
2017-01-11 11:34:51.110@<local_host>@23677 at LM_INFO@ADatP3Services|TAO (23677|1) - Transport_Cache_Manager_T::bind_i, Transport[31350240] @ hash:index{171087029:0}
2017-01-11 11:34:51.110@<local_host>@23677 at LM_INFO@ADatP3Services|TAO (23677|1) - Transport_Cache_Manager_T::bind_i: Success Transport[31350240] @ hash:index{171087029:0}. Cache size is [1]
2017-01-11 11:34:51.110@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport_Connector::wait_for_connection_completion, going to wait for connection completion on transport[31350240]
2017-01-11 11:34:51.110@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Leader_Follower[31350240]::wait_for_event, Becoming client leader.
2017-01-11 11:34:51.110@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Leader_Follower[31350240]::wait_for_event, (leader) enter reactor event loop
2017-01-11 11:34:51.110@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - IIOP_Connection_Handler::open, The local addr is <10.50.31.100:39927>
2017-01-11 11:34:51.110@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - IIOP_Connection_Handler::open, IIOP connection to peer <10.50.31.100:30033> on 8
2017-01-11 11:34:51.110@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport::post_open, tport id changed from 31350240 to 8
2017-01-11 11:34:51.110@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport_Cache_Manager_T::mark_connected, true Transport[8]
2017-01-11 11:34:51.110@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Cache_IntId_T::recycle_state, ENTRY_CONNECTING->ENTRY_IDLE_AND_PURGABLE Transport[8] IntId=0x1de6260
2017-01-11 11:34:51.110@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - TAO_LF_CH_Event[8]::state_changed_i, state LFS_CONNECTION_WAIT->LFS_SUCCESS
2017-01-11 11:34:51.110@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Leader_Follower[31350240]::wait_for_event, (leader) exit reactor event loop
2017-01-11 11:34:51.110@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport_Connector::wait_for_connection_completion, transport [8], wait done result = 1
2017-01-11 11:34:51.110@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - IIOP_Connector::make_connection, new connected connection to <master:30033> on Transport[8]
2017-01-11 11:34:51.110@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport_Connector::connect, opening Transport[8] in TAO_CLIENT_ROLE
2017-01-11 11:34:51.110@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport_Cache_Manager_T::is_entry_available_i[8], true, state is ENTRY_IDLE_AND_PURGABLE
2017-01-11 11:34:51.110@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Cache_IntId_T::recycle_state, ENTRY_IDLE_AND_PURGABLE->ENTRY_BUSY Transport[8] IntId=0x1de6260
2017-01-11 11:34:51.110@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport_Cache_Manager_T::find_i, Found available Transport[8] @hash:index {171087029:0}
2017-01-11 11:34:51.110@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport_Connector::connect, got an existing connected Transport[8] in role TAO_CLIENT_ROLE
2017-01-11 11:34:51.110@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Codeset_Manager_i::set_tcs, No codeset component in profile
2017-01-11 11:34:51.110@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Exclusive_TMS::request_id - <1>
2017-01-11 11:34:51.110@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Codeset_Manager_i::generate_service_context, using tcs_c <ISO8859_1> (00010001), tcs_w <UTF-16> (00010109)
2017-01-11 11:34:51.110@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - GIOP_Message_Base::dump_msg, send GIOP message v1.0, 111 data bytes, my endian, Type Request[1]
2017-01-11 11:34:51.110@<local_host>@23677 at LM_DEBUG@GIOP message - HEXDUMP 123 bytes
47 49 4f 50 01 00 01 00 6f 00 00 00 01 00 00 00 GIOP....o.......
01 00 00 00 0c 00 00 00 01 44 61 74 01 00 01 00 .........Dat....
09 01 01 00 01 00 00 00 01 00 00 00 0b 00 00 00 ................
4e 61 6d 65 53 65 72 76 69 63 65 00 06 00 00 00 NameService.....
5f 69 73 5f 61 00 00 00 00 00 00 00 2b 00 00 00 _is_a.......+...
49 44 4c 3a 6f 6d 67 2e 6f 72 67 2f 43 6f 73 4e IDL:omg.org/CosN
61 6d 69 6e 67 2f 4e 61 6d 69 6e 67 43 6f 6e 74 aming/NamingCont
65 78 74 45 78 74 3a 31 2e 30 00 extExt:1.0.
2017-01-11 11:34:51.110@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[8]::drain_queue_helper, sending 1 buffers
2017-01-11 11:34:51.110@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[8]::drain_queue_helper, buffer 0/1 has 123 bytes
2017-01-11 11:34:51.110@<local_host>@23677 at LM_DEBUG@TAO - Transport[8]::drain_queue_helper (0/123) - HEXDUMP 123 bytes
47 49 4f 50 01 00 01 00 6f 00 00 00 01 00 00 00 GIOP....o.......
01 00 00 00 0c 00 00 00 01 44 61 74 01 00 01 00 .........Dat....
09 01 01 00 01 00 00 00 01 00 00 00 0b 00 00 00 ................
4e 61 6d 65 53 65 72 76 69 63 65 00 06 00 00 00 NameService.....
5f 69 73 5f 61 00 00 00 00 00 00 00 2b 00 00 00 _is_a.......+...
49 44 4c 3a 6f 6d 67 2e 6f 72 67 2f 43 6f 73 4e IDL:omg.org/CosN
61 6d 69 6e 67 2f 4e 61 6d 69 6e 67 43 6f 6e 74 aming/NamingCont
65 78 74 45 78 74 3a 31 2e 30 00 extExt:1.0.
2017-01-11 11:34:51.110@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[8]::drain_queue_helper, end of data
2017-01-11 11:34:51.110@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[8]::cleanup_queue, byte_count = 123
2017-01-11 11:34:51.110@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[8]::cleanup_queue, after transfer, bc = 0, all_sent = 1, ml = 0
2017-01-11 11:34:51.110@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[8]::drain_queue_helper, byte_count = 123, head_is_empty = 1
2017-01-11 11:34:51.111@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[8]::drain_queue_i, helper retval = 1
2017-01-11 11:34:51.111@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[8]::handle_input
2017-01-11 11:34:51.111@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[8]::process_queue_head, 0 enqueued
2017-01-11 11:34:51.111@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[8]::handle_input_parse_data, enter
2017-01-11 11:34:51.111@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[8]::handle_input_parse_data, read 192 bytes
2017-01-11 11:34:51.111@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - GIOP_Message_State::parse_message_header_i
2017-01-11 11:34:51.111@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - GIOP_Message_State::get_version_info
2017-01-11 11:34:51.111@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - GIOP_Message_State::get_byte_order_info
2017-01-11 11:34:51.111@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[8]::process_parsed_messages, entering (missing data == 0)
2017-01-11 11:34:51.111@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - GIOP_Message_Base::dump_msg, Transport[8] recv GIOP message v1.0, 180 data bytes, my endian, Type Reply[1]
2017-01-11 11:34:51.111@<local_host>@23677 at LM_DEBUG@GIOP message - HEXDUMP 192 bytes
47 49 4f 50 01 00 01 01 b4 00 00 00 00 00 00 00 GIOP............
01 00 00 00 03 00 00 00 2b 00 00 00 49 44 4c 3a ........+...IDL:
6f 6d 67 2e 6f 72 67 2f 43 6f 73 4e 61 6d 69 6e omg.org/CosNamin
67 2f 4e 61 6d 69 6e 67 43 6f 6e 74 65 78 74 45 g/NamingContextE
78 74 3a 31 2e 30 00 00 01 00 00 00 00 00 00 00 xt:1.0..........
6c 00 00 00 01 01 02 00 0d 00 00 00 31 30 2e 35 l...........10.5
30 2e 33 31 2e 31 30 30 00 a0 51 75 3a 00 00 00 0.31.100..Qu:...
14 01 0f 00 4e 55 50 00 00 00 1c 00 00 00 00 01 ....NUP.........
00 00 00 52 6f 6f 74 50 4f 41 00 4e 61 6d 65 53 ...RootPOA.NameS
65 72 76 69 63 65 00 00 00 00 00 01 00 00 00 4e ervice.........N
61 6d 65 53 65 72 76 69 63 65 01 00 01 00 00 00 ameService......
00 00 00 00 08 00 00 00 01 00 00 00 00 4f 41 54 .............OAT
2017-01-11 11:34:51.111@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Synch_Twoway_Invocation::location_forward being handled
2017-01-11 11:34:51.111@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Stub::base_profiles, acquired profile lock this = 0x1e14e40
2017-01-11 11:34:51.111@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[8]::make_idle
2017-01-11 11:34:51.111@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Cache_IntId_T::recycle_state, ENTRY_BUSY->ENTRY_IDLE_AND_PURGABLE Transport[8] IntId=0x1de6260
2017-01-11 11:34:51.111@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Stub::add_forward_profiles, acquired profile lock this = 0x1dd2200
2017-01-11 11:34:51.111@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Stub::next_profile, acquired profile lock this = 0x1dd2200
2017-01-11 11:34:51.111@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Stub::next_profile_i, released profile lock to reinitialize this = 0x1dd2200
2017-01-11 11:34:51.111@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Stub::next_profile_i, reacquired profile lock on object this = 0x1dd2200
2017-01-11 11:34:51.111@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Invocation_Adapter::invoke_i, handling forwarded locations
2017-01-11 11:34:51.111@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Invocation_Adapter::invoke_i, making a TAO_CS_REMOTE_STRATEGY invocation
2017-01-11 11:34:51.111@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=TAO_ORB_Core_Static_Resources type=0x1db1a30 => 0x1db17a0
2017-01-11 11:34:51.111@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport_Cache_Manager_T::fill_set_i, current_size = 1, cache_maximum = 32768
2017-01-11 11:34:51.111@<local_host>@23677 at LM_INFO@ADatP3Services|TAO (23677|1) - Transport_Cache_Manager_T::purge, Cache size after purging is [1]
2017-01-11 11:34:51.111@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - IIOP_Connector::begin_connection, to <10.50.31.100:30033> which should block
2017-01-11 11:34:51.111@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - TAO_LF_CH_Event[0]::state_changed_i, state LFS_IDLE->LFS_CONNECTION_WAIT
2017-01-11 11:34:51.111@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - IIOP_Connection_Handler[31352080]::IIOP_Connection_Handler, this=0x1de6380
2017-01-11 11:34:51.111@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport_Connector::wait_for_connection_completion, transport [31352080], Connection not complete.
2017-01-11 11:34:51.111@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Cache_IntId_T::Cache_IntId_T, this=0x7ffe091c3120 Transport[31352080] is not connected
2017-01-11 11:34:51.111@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Cache_IntId_T::recycle_state, ENTRY_UNKNOWN->ENTRY_CONNECTING Transport[31352080] IntId=0x7ffe091c3120
2017-01-11 11:34:51.111@<local_host>@23677 at LM_INFO@ADatP3Services|TAO (23677|1) - Transport_Cache_Manager_T::bind_i, Transport[31352080] @ hash:index{171087029:0}
2017-01-11 11:34:51.111@<local_host>@23677 at LM_INFO@ADatP3Services|TAO (23677|1) - Transport_Cache_Manager_T::bind_i: Success Transport[31352080] @ hash:index{171087029:0}. Cache size is [2]
2017-01-11 11:34:51.111@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport_Connector::wait_for_connection_completion, going to wait for connection completion on transport[31352080]
2017-01-11 11:34:51.111@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Leader_Follower[31352080]::wait_for_event, Becoming client leader.
2017-01-11 11:34:51.112@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Leader_Follower[31352080]::wait_for_event, (leader) enter reactor event loop
2017-01-11 11:34:51.112@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - IIOP_Connection_Handler::open, The local addr is <10.50.31.100:42585>
2017-01-11 11:34:51.112@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - IIOP_Connection_Handler::open, IIOP connection to peer <10.50.31.100:30033> on 9
2017-01-11 11:34:51.112@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport::post_open, tport id changed from 31352080 to 9
2017-01-11 11:34:51.112@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport_Cache_Manager_T::mark_connected, true Transport[9]
2017-01-11 11:34:51.112@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Cache_IntId_T::recycle_state, ENTRY_CONNECTING->ENTRY_IDLE_AND_PURGABLE Transport[9] IntId=0x1e1d200
2017-01-11 11:34:51.112@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - TAO_LF_CH_Event[9]::state_changed_i, state LFS_CONNECTION_WAIT->LFS_SUCCESS
2017-01-11 11:34:51.112@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Leader_Follower[31352080]::wait_for_event, (leader) exit reactor event loop
2017-01-11 11:34:51.112@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport_Connector::wait_for_connection_completion, transport [9], wait done result = 1
2017-01-11 11:34:51.112@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - IIOP_Connector::make_connection, new connected connection to <10.50.31.100:30033> on Transport[9]
2017-01-11 11:34:51.112@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport_Connector::connect, opening Transport[9] in TAO_CLIENT_ROLE
2017-01-11 11:34:51.112@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport_Cache_Manager_T::is_entry_available_i[9], true, state is ENTRY_IDLE_AND_PURGABLE
2017-01-11 11:34:51.112@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Cache_IntId_T::recycle_state, ENTRY_IDLE_AND_PURGABLE->ENTRY_BUSY Transport[9] IntId=0x1e1d200
2017-01-11 11:34:51.112@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport_Cache_Manager_T::find_i, Found available Transport[9] @hash:index {171087029:0}
2017-01-11 11:34:51.112@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport_Connector::connect, got an existing connected Transport[9] in role TAO_CLIENT_ROLE
2017-01-11 11:34:51.112@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Codeset_Manager_i::set_tcs, No codeset component in profile
2017-01-11 11:34:51.112@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Exclusive_TMS::request_id - <1>
2017-01-11 11:34:51.112@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Codeset_Manager_i::generate_service_context, using tcs_c <ISO8859_1> (00010001), tcs_w <UTF-16> (00010109)
2017-01-11 11:34:51.112@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - GIOP_Message_Base::dump_msg, send GIOP message v1.2, 163 data bytes, my endian, Type Request[1]
2017-01-11 11:34:51.112@<local_host>@23677 at LM_DEBUG@GIOP message - HEXDUMP 175 bytes
47 49 4f 50 01 02 01 00 a3 00 00 00 01 00 00 00 GIOP............
03 00 00 00 00 00 00 00 3a 00 00 00 14 01 0f 00 ........:.......
4e 55 50 00 00 00 1c 00 00 00 00 01 00 00 00 52 NUP............R
6f 6f 74 50 4f 41 00 4e 61 6d 65 53 65 72 76 69 ootPOA.NameServi
63 65 00 00 00 00 00 01 00 00 00 4e 61 6d 65 53 ce.........NameS
65 72 76 69 63 65 00 00 06 00 00 00 5f 69 73 5f ervice......_is_
61 00 00 00 01 00 00 00 01 00 00 00 0c 00 00 00 a...............
01 44 61 74 01 00 01 00 09 01 01 00 00 00 00 00 .Dat............
2b 00 00 00 49 44 4c 3a 6f 6d 67 2e 6f 72 67 2f +...IDL:omg.org/
43 6f 73 4e 61 6d 69 6e 67 2f 4e 61 6d 69 6e 67 CosNaming/Naming
43 6f 6e 74 65 78 74 45 78 74 3a 31 2e 30 00 ContextExt:1.0.
2017-01-11 11:34:51.112@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::drain_queue_helper, sending 1 buffers
2017-01-11 11:34:51.112@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::drain_queue_helper, buffer 0/1 has 175 bytes
2017-01-11 11:34:51.112@<local_host>@23677 at LM_DEBUG@TAO - Transport[9]::drain_queue_helper (0/175) - HEXDUMP 175 bytes
47 49 4f 50 01 02 01 00 a3 00 00 00 01 00 00 00 GIOP............
03 00 00 00 00 00 00 00 3a 00 00 00 14 01 0f 00 ........:.......
4e 55 50 00 00 00 1c 00 00 00 00 01 00 00 00 52 NUP............R
6f 6f 74 50 4f 41 00 4e 61 6d 65 53 65 72 76 69 ootPOA.NameServi
63 65 00 00 00 00 00 01 00 00 00 4e 61 6d 65 53 ce.........NameS
65 72 76 69 63 65 00 00 06 00 00 00 5f 69 73 5f ervice......_is_
61 00 00 00 01 00 00 00 01 00 00 00 0c 00 00 00 a...............
01 44 61 74 01 00 01 00 09 01 01 00 00 00 00 00 .Dat............
2b 00 00 00 49 44 4c 3a 6f 6d 67 2e 6f 72 67 2f +...IDL:omg.org/
43 6f 73 4e 61 6d 69 6e 67 2f 4e 61 6d 69 6e 67 CosNaming/Naming
43 6f 6e 74 65 78 74 45 78 74 3a 31 2e 30 00 ContextExt:1.0.
2017-01-11 11:34:51.112@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::drain_queue_helper, end of data
2017-01-11 11:34:51.112@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::cleanup_queue, byte_count = 175
2017-01-11 11:34:51.112@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::cleanup_queue, after transfer, bc = 0, all_sent = 1, ml = 0
2017-01-11 11:34:51.112@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::drain_queue_helper, byte_count = 175, head_is_empty = 1
2017-01-11 11:34:51.112@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::drain_queue_i, helper retval = 1
2017-01-11 11:34:51.112@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::handle_input
2017-01-11 11:34:51.112@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::process_queue_head, 0 enqueued
2017-01-11 11:34:51.112@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::handle_input_parse_data, enter
2017-01-11 11:34:51.112@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::handle_input_parse_data, read 25 bytes
2017-01-11 11:34:51.112@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - GIOP_Message_State::parse_message_header_i
2017-01-11 11:34:51.112@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - GIOP_Message_State::get_version_info
2017-01-11 11:34:51.112@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - GIOP_Message_State::get_byte_order_info
2017-01-11 11:34:51.112@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::process_parsed_messages, entering (missing data == 0)
2017-01-11 11:34:51.112@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - GIOP_Message_Base::dump_msg, Transport[9] recv GIOP message v1.2, 13 data bytes, my endian, Type Reply[1]
2017-01-11 11:34:51.112@<local_host>@23677 at LM_DEBUG@GIOP message - HEXDUMP 25 bytes
47 49 4f 50 01 02 01 01 0d 00 00 00 01 00 00 00 GIOP............
00 00 00 00 00 00 00 00 01 .........
2017-01-11 11:34:51.112@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::make_idle
2017-01-11 11:34:51.112@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Cache_IntId_T::recycle_state, ENTRY_BUSY->ENTRY_IDLE_AND_PURGABLE Transport[9] IntId=0x1e1d200
2017-01-11 11:34:51.112@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) SCG:<dtor=0x7ffe091c3670> - new repo=0x1dab0b0
2017-01-11 11:34:51.112@<local_host>@23677 at LM_DEBUG@ADatP3Services| ./src/ServiceDriver.cpp:50 - Service driver initialized
2017-01-11 11:34:51.112@<local_host>@23677 at LM_DEBUG@ADatP3Services| ./src/NamingClient.cpp:178 - Prepare context item 1 - ADatP3Services.
2017-01-11 11:34:51.112@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) - SCG:<ctor=0x7ffe091c3680> - config=0x1dab040 repo=0x1dab0b0 superceded by repo=0x1dab0b0
2017-01-11 11:34:51.112@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Invocation_Adapter::invoke_i, making a TAO_CS_REMOTE_STRATEGY invocation
2017-01-11 11:34:51.112@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=TAO_ORB_Core_Static_Resources type=0x1db1a30 => 0x1db17a0
2017-01-11 11:34:51.112@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport_Cache_Manager_T::is_entry_available_i[9], true, state is ENTRY_IDLE_AND_PURGABLE
2017-01-11 11:34:51.112@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Cache_IntId_T::recycle_state, ENTRY_IDLE_AND_PURGABLE->ENTRY_BUSY Transport[9] IntId=0x1e1d200
2017-01-11 11:34:51.112@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport_Cache_Manager_T::find_i, Found available Transport[9] @hash:index {171087029:0}
2017-01-11 11:34:51.112@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport_Connector::connect, got an existing connected Transport[9] in role TAO_CLIENT_ROLE
2017-01-11 11:34:51.112@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Exclusive_TMS::request_id - <2>
2017-01-11 11:34:51.112@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - GIOP_Message_Base::dump_msg, send GIOP message v1.2, 137 data bytes, my endian, Type Request[2]
2017-01-11 11:34:51.112@<local_host>@23677 at LM_DEBUG@GIOP message - HEXDUMP 149 bytes
47 49 4f 50 01 02 01 00 89 00 00 00 02 00 00 00 GIOP............
03 00 00 00 00 00 00 00 3a 00 00 00 14 01 0f 00 ........:.......
4e 55 50 00 00 00 1c 00 00 00 00 01 00 00 00 52 NUP............R
6f 6f 74 50 4f 41 00 4e 61 6d 65 53 65 72 76 69 ootPOA.NameServi
63 65 00 00 00 00 00 01 00 00 00 4e 61 6d 65 53 ce.........NameS
65 72 76 69 63 65 00 00 11 00 00 00 62 69 6e 64 ervice......bind
5f 6e 65 77 5f 63 6f 6e 74 65 78 74 00 00 00 00 _new_context....
00 00 00 00 01 00 01 00 01 00 00 00 0f 00 00 00 ................
41 44 61 74 50 33 53 65 72 76 69 63 65 73 00 2f ADatP3Services./
01 00 00 00 00 .....
2017-01-11 11:34:51.112@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::drain_queue_helper, sending 1 buffers
2017-01-11 11:34:51.112@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::drain_queue_helper, buffer 0/1 has 149 bytes
2017-01-11 11:34:51.113@<local_host>@23677 at LM_DEBUG@TAO - Transport[9]::drain_queue_helper (0/149) - HEXDUMP 149 bytes
47 49 4f 50 01 02 01 00 89 00 00 00 02 00 00 00 GIOP............
03 00 00 00 00 00 00 00 3a 00 00 00 14 01 0f 00 ........:.......
4e 55 50 00 00 00 1c 00 00 00 00 01 00 00 00 52 NUP............R
6f 6f 74 50 4f 41 00 4e 61 6d 65 53 65 72 76 69 ootPOA.NameServi
63 65 00 00 00 00 00 01 00 00 00 4e 61 6d 65 53 ce.........NameS
65 72 76 69 63 65 00 00 11 00 00 00 62 69 6e 64 ervice......bind
5f 6e 65 77 5f 63 6f 6e 74 65 78 74 00 00 00 00 _new_context....
00 00 00 00 01 00 01 00 01 00 00 00 0f 00 00 00 ................
41 44 61 74 50 33 53 65 72 76 69 63 65 73 00 2f ADatP3Services./
01 00 00 00 00 .....
2017-01-11 11:34:51.113@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::drain_queue_helper, end of data
2017-01-11 11:34:51.113@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::cleanup_queue, byte_count = 149
2017-01-11 11:34:51.113@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::cleanup_queue, after transfer, bc = 0, all_sent = 1, ml = 0
2017-01-11 11:34:51.113@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::drain_queue_helper, byte_count = 149, head_is_empty = 1
2017-01-11 11:34:51.113@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::drain_queue_i, helper retval = 1
2017-01-11 11:34:51.113@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::handle_input
2017-01-11 11:34:51.113@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::process_queue_head, 0 enqueued
2017-01-11 11:34:51.113@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::handle_input_parse_data, enter
2017-01-11 11:34:51.114@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::handle_input_parse_data, read 81 bytes
2017-01-11 11:34:51.114@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - GIOP_Message_State::parse_message_header_i
2017-01-11 11:34:51.114@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - GIOP_Message_State::get_version_info
2017-01-11 11:34:51.114@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - GIOP_Message_State::get_byte_order_info
2017-01-11 11:34:51.114@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::process_parsed_messages, entering (missing data == 0)
2017-01-11 11:34:51.114@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - GIOP_Message_Base::dump_msg, Transport[9] recv GIOP message v1.2, 69 data bytes, my endian, Type Reply[2]
2017-01-11 11:34:51.114@<local_host>@23677 at LM_DEBUG@GIOP message - HEXDUMP 81 bytes
47 49 4f 50 01 02 01 01 45 00 00 00 02 00 00 00 GIOP....E.......
01 00 00 00 00 00 00 00 35 00 00 00 49 44 4c 3a ........5...IDL:
6f 6d 67 2e 6f 72 67 2f 43 6f 73 4e 61 6d 69 6e omg.org/CosNamin
67 2f 4e 61 6d 69 6e 67 43 6f 6e 74 65 78 74 2f g/NamingContext/
41 6c 72 65 61 64 79 42 6f 75 6e 64 3a 31 2e 30 AlreadyBound:1.0
00 .
2017-01-11 11:34:51.114@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Synch_Twoway_Invocation::handle_user_exception
2017-01-11 11:34:51.115@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Synch_Twoway_Invocation::handle_user_exception - raising exception IDL:omg.org/CosNaming/NamingContext/AlreadyBound:1.0
2017-01-11 11:34:51.115@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::make_idle
2017-01-11 11:34:51.115@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Cache_IntId_T::recycle_state, ENTRY_BUSY->ENTRY_IDLE_AND_PURGABLE Transport[9] IntId=0x1e1d200
2017-01-11 11:34:51.115@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) SCG:<dtor=0x7ffe091c3680> - new repo=0x1dab0b0
2017-01-11 11:34:51.115@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) - SCG:<ctor=0x7ffe091c3560> - config=0x1dab040 repo=0x1dab0b0 superceded by repo=0x1dab0b0
2017-01-11 11:34:51.115@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Invocation_Adapter::invoke_i, making a TAO_CS_REMOTE_STRATEGY invocation
2017-01-11 11:34:51.115@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=TAO_ORB_Core_Static_Resources type=0x1db1a30 => 0x1db17a0
2017-01-11 11:34:51.115@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport_Cache_Manager_T::is_entry_available_i[9], true, state is ENTRY_IDLE_AND_PURGABLE
2017-01-11 11:34:51.115@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Cache_IntId_T::recycle_state, ENTRY_IDLE_AND_PURGABLE->ENTRY_BUSY Transport[9] IntId=0x1e1d200
2017-01-11 11:34:51.115@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport_Cache_Manager_T::find_i, Found available Transport[9] @hash:index {171087029:0}
2017-01-11 11:34:51.115@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport_Connector::connect, got an existing connected Transport[9] in role TAO_CLIENT_ROLE
2017-01-11 11:34:51.115@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Exclusive_TMS::request_id - <3>
2017-01-11 11:34:51.115@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - GIOP_Message_Base::dump_msg, send GIOP message v1.2, 96 data bytes, my endian, Type Request[3]
2017-01-11 11:34:51.115@<local_host>@23677 at LM_DEBUG@GIOP message - HEXDUMP 108 bytes
47 49 4f 50 01 02 01 00 60 00 00 00 03 00 00 00 GIOP....`.......
03 00 00 00 00 00 00 00 3a 00 00 00 14 01 0f 00 ........:.......
4e 55 50 00 00 00 1c 00 00 00 00 01 00 00 00 52 NUP............R
6f 6f 74 50 4f 41 00 4e 61 6d 65 53 65 72 76 69 ootPOA.NameServi
63 65 00 00 00 00 00 01 00 00 00 4e 61 6d 65 53 ce.........NameS
65 72 76 69 63 65 00 00 05 00 00 00 6c 69 73 74 ervice......list
00 6e 65 77 00 00 00 00 64 00 00 00 .new....d...
2017-01-11 11:34:51.115@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::drain_queue_helper, sending 1 buffers
2017-01-11 11:34:51.115@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::drain_queue_helper, buffer 0/1 has 108 bytes
2017-01-11 11:34:51.115@<local_host>@23677 at LM_DEBUG@TAO - Transport[9]::drain_queue_helper (0/108) - HEXDUMP 108 bytes
47 49 4f 50 01 02 01 00 60 00 00 00 03 00 00 00 GIOP....`.......
03 00 00 00 00 00 00 00 3a 00 00 00 14 01 0f 00 ........:.......
4e 55 50 00 00 00 1c 00 00 00 00 01 00 00 00 52 NUP............R
6f 6f 74 50 4f 41 00 4e 61 6d 65 53 65 72 76 69 ootPOA.NameServi
63 65 00 00 00 00 00 01 00 00 00 4e 61 6d 65 53 ce.........NameS
65 72 76 69 63 65 00 00 05 00 00 00 6c 69 73 74 ervice......list
00 6e 65 77 00 00 00 00 64 00 00 00 .new....d...
2017-01-11 11:34:51.115@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::drain_queue_helper, end of data
2017-01-11 11:34:51.115@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::cleanup_queue, byte_count = 108
2017-01-11 11:34:51.115@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::cleanup_queue, after transfer, bc = 0, all_sent = 1, ml = 0
2017-01-11 11:34:51.115@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::drain_queue_helper, byte_count = 108, head_is_empty = 1
2017-01-11 11:34:51.115@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::drain_queue_i, helper retval = 1
2017-01-11 11:34:51.115@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::handle_input
2017-01-11 11:34:51.115@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::process_queue_head, 0 enqueued
2017-01-11 11:34:51.115@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::handle_input_parse_data, enter
2017-01-11 11:34:51.115@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::handle_input_parse_data, read 1032 bytes
2017-01-11 11:34:51.115@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - GIOP_Message_State::parse_message_header_i
2017-01-11 11:34:51.115@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - GIOP_Message_State::get_version_info
2017-01-11 11:34:51.115@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - GIOP_Message_State::get_byte_order_info
2017-01-11 11:34:51.115@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::handle_input
2017-01-11 11:34:51.115@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::process_queue_head, 0 enqueued
2017-01-11 11:34:51.115@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::handle_input_missing_data_message, enter (missing data == 420)
2017-01-11 11:34:51.115@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::handle_input_missing_data_message, read bytes 420
2017-01-11 11:34:51.115@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::process_parsed_messages, entering (missing data == 0)
2017-01-11 11:34:51.115@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - GIOP_Message_Base::dump_msg, Transport[9] recv GIOP message v1.2, 1440 data bytes, my endian, Type Reply[3]
2017-01-11 11:34:51.116@<local_host>@23677 at LM_DEBUG@GIOP message - HEXDUMP 1452 bytes (showing first 912 bytes)
47 49 4f 50 01 02 01 01 a0 05 00 00 03 00 00 00 GIOP............
00 00 00 00 00 00 00 00 23 00 00 00 01 00 00 00 ........#.......
17 00 00 00 54 72 61 63 6b 4e 75 6d 62 65 72 4d ....TrackNumberM
61 6e 69 70 75 6c 61 74 6f 72 00 74 01 00 00 00 anipulator.t....
00 6c 72 65 00 00 00 00 01 00 00 00 0b 00 00 00 .lre............
53 32 4e 4d 6f 6e 69 74 6f 72 00 00 01 00 00 00 S2NMonitor......
00 2e 33 31 00 00 00 00 01 00 00 00 13 00 00 00 ..31............
4d 4f 5a 49 52 53 65 72 76 69 63 65 53 65 72 76 MOZIRServiceServ
65 72 00 52 01 00 00 00 00 41 00 4e 00 00 00 00 er.R.....A.N....
01 00 00 00 15 00 00 00 41 44 61 74 50 33 53 65 ........ADatP3Se
72 76 69 63 65 73 54 65 73 74 44 53 00 00 00 00 rvicesTestDS....
01 00 00 00 00 00 00 00 01 00 00 00 01 00 00 00 ................
11 00 00 00 4d 57 4f 5f 48 4f 4d 45 5f 53 45 52 ....MWO_HOME_SER
56 49 43 45 00 00 00 00 01 00 00 00 00 53 54 1b VICE.........ST.
00 00 00 00 01 00 00 00 11 00 00 00 41 43 4f 5f ............ACO_
48 4f 4d 45 5f 53 45 52 56 49 43 45 00 08 ce e8 HOME_SERVICE....
01 00 00 00 00 00 00 00 00 00 00 00 01 00 00 00 ................
1b 00 00 00 54 72 61 63 6b 41 74 74 72 69 62 75 ....TrackAttribu
74 65 73 4d 61 6e 69 70 75 6c 61 74 6f 72 00 00 tesManipulator..
01 00 00 00 00 00 00 00 00 00 00 00 01 00 00 00 ................
14 00 00 00 4d 4f 56 52 45 51 5f 48 4f 4d 45 5f ....MOVREQ_HOME_
53 45 52 56 49 43 45 00 01 00 00 00 00 51 5f 48 SERVICE......Q_H
00 00 00 00 01 00 00 00 19 00 00 00 43 4f 4c 4c ............COLL
45 43 54 49 4f 4e 53 5f 48 4f 4d 45 5f 53 45 52 ECTIONS_HOME_SER
56 49 43 45 00 4f 42 53 01 00 00 00 00 5f 53 45 VICE.OBS....._SE
00 00 00 00 01 00 00 00 19 00 00 00 47 45 4f 4c ............GEOL
4f 43 41 54 49 4f 4e 5f 48 4f 4d 45 5f 53 45 52 OCATION_HOME_SER
56 49 43 45 00 4e 53 5f 01 00 00 00 00 00 00 00 VICE.NS_........
00 00 00 00 01 00 00 00 0a 00 00 00 4e 4f 44 45 ............NODE
5f 30 30 31 36 00 00 00 01 00 00 00 00 00 00 00 _0016...........
01 00 00 00 01 00 00 00 17 00 00 00 52 41 44 41 ............RADA
52 54 59 50 45 5f 48 4f 4d 45 5f 53 45 52 56 49 RTYPE_HOME_SERVI
43 45 00 00 01 00 00 00 00 55 50 00 00 00 00 00 CE.......UP.....
01 00 00 00 0a 00 00 00 4e 4f 44 45 5f 30 30 31 ........NODE_001
31 00 65 53 01 00 00 00 00 65 00 00 01 00 00 00 1.eS.....e......
01 00 00 00 13 00 00 00 43 4f 4c 4c 45 43 54 49 ........COLLECTI
4f 4e 5f 53 45 52 56 49 43 45 00 5f 01 00 00 00 ON_SERVICE._....
00 00 00 00 00 00 00 00 01 00 00 00 11 00 00 00 ................
4d 43 52 5f 48 4f 4d 45 5f 53 45 52 56 49 43 45 MCR_HOME_SERVICE
00 00 00 00 01 00 00 00 00 6f 72 6d 00 00 00 00 .........orm....
01 00 00 00 13 00 00 00 53 53 52 45 50 5f 48 4f ........SSREP_HO
4d 45 5f 53 45 52 56 49 43 45 00 65 01 00 00 00 ME_SERVICE.e....
00 74 53 65 00 00 00 00 01 00 00 00 13 00 00 00 .tSe............
53 52 52 45 50 5f 48 4f 4d 45 5f 53 45 52 56 49 SRREP_HOME_SERVI
43 45 00 00 01 00 00 00 00 00 00 00 00 00 00 00 CE..............
01 00 00 00 0f 00 00 00 50 72 6f 63 65 73 73 4d ........ProcessM
6f 6e 69 74 6f 72 00 a0 01 00 00 00 00 01 0f 00 onitor..........
00 00 00 00 01 00 00 00 11 00 00 00 54 72 61 63 ............Trac
6b 4d 61 6e 69 70 75 6c 61 74 6f 72 00 00 00 00 kManipulator....
01 00 00 00 00 4f 41 54 00 00 00 00 01 00 00 00 .....OAT........
11 00 00 00 46 55 54 5f 48 4f 4d 45 5f 53 45 52 ....FUT_HOME_SER
56 49 43 45 00 00 00 00 01 00 00 00 00 00 00 00 VICE............
00 00 00 00 01 00 00 00 16 00 00 00 53 70 72 65 ............Spre
61 64 44 61 65 6d 6f 6e 43 6f 6e 74 72 6f 6c 65 adDaemonControle
72 00 54 1b 01 00 00 00 00 07 00 01 00 00 00 00 r.T.............
01 00 00 00 11 00 00 00 43 4d 4f 5f 48 4f 4d 45 ........CMO_HOME
5f 53 45 52 56 49 43 45 00 00 00 00 01 00 00 00 _SERVICE........
2017-01-11 11:34:51.116@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::make_idle
2017-01-11 11:34:51.116@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Cache_IntId_T::recycle_state, ENTRY_BUSY->ENTRY_IDLE_AND_PURGABLE Transport[9] IntId=0x1e1d200
2017-01-11 11:34:51.116@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) SCG:<dtor=0x7ffe091c3560> - new repo=0x1dab0b0
2017-01-11 11:34:51.116@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) - SCG:<ctor=0x7ffe091c35a0> - config=0x1dab040 repo=0x1dab0b0 superceded by repo=0x1dab0b0
2017-01-11 11:34:51.116@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Invocation_Adapter::invoke_i, making a TAO_CS_REMOTE_STRATEGY invocation
2017-01-11 11:34:51.116@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=TAO_ORB_Core_Static_Resources type=0x1db1a30 => 0x1db17a0
2017-01-11 11:34:51.116@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport_Cache_Manager_T::is_entry_available_i[9], true, state is ENTRY_IDLE_AND_PURGABLE
2017-01-11 11:34:51.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Cache_IntId_T::recycle_state, ENTRY_IDLE_AND_PURGABLE->ENTRY_BUSY Transport[9] IntId=0x1e1d200
2017-01-11 11:34:51.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport_Cache_Manager_T::find_i, Found available Transport[9] @hash:index {171087029:0}
2017-01-11 11:34:51.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport_Connector::connect, got an existing connected Transport[9] in role TAO_CLIENT_ROLE
2017-01-11 11:34:51.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Exclusive_TMS::request_id - <4>
2017-01-11 11:34:51.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - GIOP_Message_Base::dump_msg, send GIOP message v1.2, 121 data bytes, my endian, Type Request[4]
2017-01-11 11:34:51.117@<local_host>@23677 at LM_DEBUG@GIOP message - HEXDUMP 133 bytes
47 49 4f 50 01 02 01 00 79 00 00 00 04 00 00 00 GIOP....y.......
03 00 00 00 00 00 00 00 3a 00 00 00 14 01 0f 00 ........:.......
4e 55 50 00 00 00 1c 00 00 00 00 01 00 00 00 52 NUP............R
6f 6f 74 50 4f 41 00 4e 61 6d 65 53 65 72 76 69 ootPOA.NameServi
63 65 00 00 00 00 00 01 00 00 00 4e 61 6d 65 53 ce.........NameS
65 72 76 69 63 65 00 00 08 00 00 00 72 65 73 6f ervice......reso
6c 76 65 00 00 00 00 00 01 00 00 00 0f 00 00 00 lve.............
41 44 61 74 50 33 53 65 72 76 69 63 65 73 00 00 ADatP3Services..
01 00 00 00 00 .....
2017-01-11 11:34:51.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::drain_queue_helper, sending 1 buffers
2017-01-11 11:34:51.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::drain_queue_helper, buffer 0/1 has 133 bytes
2017-01-11 11:34:51.117@<local_host>@23677 at LM_DEBUG@TAO - Transport[9]::drain_queue_helper (0/133) - HEXDUMP 133 bytes
47 49 4f 50 01 02 01 00 79 00 00 00 04 00 00 00 GIOP....y.......
03 00 00 00 00 00 00 00 3a 00 00 00 14 01 0f 00 ........:.......
4e 55 50 00 00 00 1c 00 00 00 00 01 00 00 00 52 NUP............R
6f 6f 74 50 4f 41 00 4e 61 6d 65 53 65 72 76 69 ootPOA.NameServi
63 65 00 00 00 00 00 01 00 00 00 4e 61 6d 65 53 ce.........NameS
65 72 76 69 63 65 00 00 08 00 00 00 72 65 73 6f ervice......reso
6c 76 65 00 00 00 00 00 01 00 00 00 0f 00 00 00 lve.............
41 44 61 74 50 33 53 65 72 76 69 63 65 73 00 00 ADatP3Services..
01 00 00 00 00 .....
2017-01-11 11:34:51.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::drain_queue_helper, end of data
2017-01-11 11:34:51.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::cleanup_queue, byte_count = 133
2017-01-11 11:34:51.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::cleanup_queue, after transfer, bc = 0, all_sent = 1, ml = 0
2017-01-11 11:34:51.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::drain_queue_helper, byte_count = 133, head_is_empty = 1
2017-01-11 11:34:51.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::drain_queue_i, helper retval = 1
2017-01-11 11:34:51.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::handle_input
2017-01-11 11:34:51.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::process_queue_head, 0 enqueued
2017-01-11 11:34:51.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::handle_input_parse_data, enter
2017-01-11 11:34:51.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::handle_input_parse_data, read 196 bytes
2017-01-11 11:34:51.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - GIOP_Message_State::parse_message_header_i
2017-01-11 11:34:51.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - GIOP_Message_State::get_version_info
2017-01-11 11:34:51.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - GIOP_Message_State::get_byte_order_info
2017-01-11 11:34:51.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::process_parsed_messages, entering (missing data == 0)
2017-01-11 11:34:51.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - GIOP_Message_Base::dump_msg, Transport[9] recv GIOP message v1.2, 184 data bytes, my endian, Type Reply[4]
2017-01-11 11:34:51.117@<local_host>@23677 at LM_DEBUG@GIOP message - HEXDUMP 196 bytes
47 49 4f 50 01 02 01 01 b8 00 00 00 04 00 00 00 GIOP............
00 00 00 00 00 00 00 00 2b 00 00 00 49 44 4c 3a ........+...IDL:
6f 6d 67 2e 6f 72 67 2f 43 6f 73 4e 61 6d 69 6e omg.org/CosNamin
67 2f 4e 61 6d 69 6e 67 43 6f 6e 74 65 78 74 45 g/NamingContextE
78 74 3a 31 2e 30 00 00 01 00 00 00 00 00 00 00 xt:1.0..........
70 00 00 00 01 01 02 00 0d 00 00 00 31 30 2e 35 p...........10.5
30 2e 33 31 2e 31 30 30 00 a0 51 75 3d 00 00 00 0.31.100..Qu=...
14 01 0f 00 4e 55 50 00 00 00 1c 00 00 00 00 01 ....NUP.........
00 00 00 52 6f 6f 74 50 4f 41 00 4e 61 6d 65 53 ...RootPOA.NameS
65 72 76 69 63 65 00 05 00 00 00 01 00 00 00 4e ervice.........N
61 6d 65 53 65 72 76 69 63 65 5f 32 32 35 33 00 ameService_2253.
01 00 00 00 00 00 00 00 08 00 00 00 01 36 43 20 .............6C
00 4f 41 54 .OAT
2017-01-11 11:34:51.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Stub::base_profiles, acquired profile lock this = 0x1e1ecb0
2017-01-11 11:34:51.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::make_idle
2017-01-11 11:34:51.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Cache_IntId_T::recycle_state, ENTRY_BUSY->ENTRY_IDLE_AND_PURGABLE Transport[9] IntId=0x1e1d200
2017-01-11 11:34:51.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) SCG:<dtor=0x7ffe091c35a0> - new repo=0x1dab0b0
2017-01-11 11:34:51.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) - SCG:<ctor=0x7ffe091c35b0> - config=0x1dab040 repo=0x1dab0b0 superceded by repo=0x1dab0b0
2017-01-11 11:34:51.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Invocation_Adapter::invoke_i, making a TAO_CS_REMOTE_STRATEGY invocation
2017-01-11 11:34:51.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=TAO_ORB_Core_Static_Resources type=0x1db1a30 => 0x1db17a0
2017-01-11 11:34:51.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport_Cache_Manager_T::is_entry_available_i[9], true, state is ENTRY_IDLE_AND_PURGABLE
2017-01-11 11:34:51.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Cache_IntId_T::recycle_state, ENTRY_IDLE_AND_PURGABLE->ENTRY_BUSY Transport[9] IntId=0x1e1d200
2017-01-11 11:34:51.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport_Cache_Manager_T::find_i, Found available Transport[9] @hash:index {171087029:0}
2017-01-11 11:34:51.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport_Connector::connect, got an existing connected Transport[9] in role TAO_CLIENT_ROLE
2017-01-11 11:34:51.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Exclusive_TMS::request_id - <5>
2017-01-11 11:34:51.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - GIOP_Message_Base::dump_msg, send GIOP message v1.2, 144 data bytes, my endian, Type Request[5]
2017-01-11 11:34:51.117@<local_host>@23677 at LM_DEBUG@GIOP message - HEXDUMP 156 bytes
47 49 4f 50 01 02 01 00 90 00 00 00 05 00 00 00 GIOP............
03 00 00 00 00 00 00 00 3d 00 00 00 14 01 0f 00 ........=.......
4e 55 50 00 00 00 1c 00 00 00 00 01 00 00 00 52 NUP............R
6f 6f 74 50 4f 41 00 4e 61 6d 65 53 65 72 76 69 ootPOA.NameServi
63 65 00 05 00 00 00 01 00 00 00 4e 61 6d 65 53 ce.........NameS
65 72 76 69 63 65 5f 32 32 00 00 00 06 00 00 00 ervice_22.......
5f 69 73 5f 61 00 00 00 00 00 00 00 0f 00 00 00 _is_a...........
28 00 00 00 49 44 4c 3a 6f 6d 67 2e 6f 72 67 2f (...IDL:omg.org/
43 6f 73 4e 61 6d 69 6e 67 2f 4e 61 6d 69 6e 67 CosNaming/Naming
43 6f 6e 74 65 78 74 3a 31 2e 30 00 Context:1.0.
2017-01-11 11:34:51.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::drain_queue_helper, sending 1 buffers
2017-01-11 11:34:51.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::drain_queue_helper, buffer 0/1 has 156 bytes
2017-01-11 11:34:51.117@<local_host>@23677 at LM_DEBUG@TAO - Transport[9]::drain_queue_helper (0/156) - HEXDUMP 156 bytes
47 49 4f 50 01 02 01 00 90 00 00 00 05 00 00 00 GIOP............
03 00 00 00 00 00 00 00 3d 00 00 00 14 01 0f 00 ........=.......
4e 55 50 00 00 00 1c 00 00 00 00 01 00 00 00 52 NUP............R
6f 6f 74 50 4f 41 00 4e 61 6d 65 53 65 72 76 69 ootPOA.NameServi
63 65 00 05 00 00 00 01 00 00 00 4e 61 6d 65 53 ce.........NameS
65 72 76 69 63 65 5f 32 32 00 00 00 06 00 00 00 ervice_22.......
5f 69 73 5f 61 00 00 00 00 00 00 00 0f 00 00 00 _is_a...........
28 00 00 00 49 44 4c 3a 6f 6d 67 2e 6f 72 67 2f (...IDL:omg.org/
43 6f 73 4e 61 6d 69 6e 67 2f 4e 61 6d 69 6e 67 CosNaming/Naming
43 6f 6e 74 65 78 74 3a 31 2e 30 00 Context:1.0.
2017-01-11 11:34:51.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::drain_queue_helper, end of data
2017-01-11 11:34:51.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::cleanup_queue, byte_count = 156
2017-01-11 11:34:51.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::cleanup_queue, after transfer, bc = 0, all_sent = 1, ml = 0
2017-01-11 11:34:51.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::drain_queue_helper, byte_count = 156, head_is_empty = 1
2017-01-11 11:34:51.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::drain_queue_i, helper retval = 1
2017-01-11 11:34:51.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::handle_input
2017-01-11 11:34:51.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::process_queue_head, 0 enqueued
2017-01-11 11:34:51.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::handle_input_parse_data, enter
2017-01-11 11:34:51.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::handle_input_parse_data, read 25 bytes
2017-01-11 11:34:51.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - GIOP_Message_State::parse_message_header_i
2017-01-11 11:34:51.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - GIOP_Message_State::get_version_info
2017-01-11 11:34:51.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - GIOP_Message_State::get_byte_order_info
2017-01-11 11:34:51.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::process_parsed_messages, entering (missing data == 0)
2017-01-11 11:34:51.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - GIOP_Message_Base::dump_msg, Transport[9] recv GIOP message v1.2, 13 data bytes, my endian, Type Reply[5]
2017-01-11 11:34:51.118@<local_host>@23677 at LM_DEBUG@GIOP message - HEXDUMP 25 bytes
47 49 4f 50 01 02 01 01 0d 00 00 00 05 00 00 00 GIOP............
00 00 00 00 00 00 00 00 01 .........
2017-01-11 11:34:51.118@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::make_idle
2017-01-11 11:34:51.118@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Cache_IntId_T::recycle_state, ENTRY_BUSY->ENTRY_IDLE_AND_PURGABLE Transport[9] IntId=0x1e1d200
2017-01-11 11:34:51.118@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) SCG:<dtor=0x7ffe091c35b0> - new repo=0x1dab0b0
2017-01-11 11:34:52.031@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - TAO_System_Id_With_Unique_Id_Strategy::bind_using_system_id: type=IDL:IrisServices/MtfService_t:1.0, id=0x00000000010000
2017-01-11 11:34:52.031@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Stub::base_profiles, acquired profile lock this = 0x1e1d9a0
2017-01-11 11:34:52.031@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) - SCG:<ctor=0x7ffe091c3620> - config=0x1dab040 repo=0x1dab0b0 superceded by repo=0x1dab0b0
2017-01-11 11:34:52.031@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Invocation_Adapter::invoke_i, making a TAO_CS_REMOTE_STRATEGY invocation
2017-01-11 11:34:52.031@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=TAO_ORB_Core_Static_Resources type=0x1db1a30 => 0x1db17a0
2017-01-11 11:34:52.031@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport_Cache_Manager_T::is_entry_available_i[9], true, state is ENTRY_IDLE_AND_PURGABLE
2017-01-11 11:34:52.031@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Cache_IntId_T::recycle_state, ENTRY_IDLE_AND_PURGABLE->ENTRY_BUSY Transport[9] IntId=0x1e1d200
2017-01-11 11:34:52.031@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport_Cache_Manager_T::find_i, Found available Transport[9] @hash:index {171087029:0}
2017-01-11 11:34:52.031@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport_Connector::connect, got an existing connected Transport[9] in role TAO_CLIENT_ROLE
2017-01-11 11:34:52.031@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Exclusive_TMS::request_id - <6>
2017-01-11 11:34:52.032@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - GIOP_Message_Base::dump_msg, send GIOP message v1.2, 416 data bytes, my endian, Type Request[6]
2017-01-11 11:34:52.032@<local_host>@23677 at LM_DEBUG@GIOP message - HEXDUMP 428 bytes
47 49 4f 50 01 02 01 00 a0 01 00 00 06 00 00 00 GIOP............
03 00 00 00 00 00 00 00 3a 00 00 00 14 01 0f 00 ........:.......
4e 55 50 00 00 00 1c 00 00 00 00 01 00 00 00 52 NUP............R
6f 6f 74 50 4f 41 00 4e 61 6d 65 53 65 72 76 69 ootPOA.NameServi
63 65 00 00 00 00 00 01 00 00 00 4e 61 6d 65 53 ce.........NameS
65 72 76 69 63 65 5f 32 07 00 00 00 72 65 62 69 ervice_2....rebi
6e 64 00 5f 00 00 00 00 02 00 00 00 0f 00 00 00 nd._............
41 44 61 74 50 33 53 65 72 76 69 63 65 73 00 2f ADatP3Services./
01 00 00 00 00 6d 69 6e 0b 00 00 00 4d 54 46 53 .....min....MTFS
65 72 76 69 63 65 00 3a 01 00 00 00 00 69 6e 67 ervice.:.....ing
22 00 00 00 49 44 4c 3a 49 72 69 73 53 65 72 76 "...IDL:IrisServ
69 63 65 73 2f 4d 74 66 53 65 72 76 69 63 65 5f ices/MtfService_
74 3a 31 2e 30 00 00 00 02 00 00 00 00 00 00 00 t:1.0...........
68 00 00 00 01 01 02 00 0c 00 00 00 31 30 2e 35 h...........10.5
30 2e 33 31 2e 31 36 00 5f ac 00 00 17 00 00 00 0.31.16._.......
14 01 0f 00 52 53 54 4b 0a 76 58 88 a6 01 00 00 ....RSTK.vX.....
00 00 00 01 00 00 00 00 02 00 00 00 00 00 00 00 ................
08 00 00 00 01 9a 07 01 00 4f 41 54 01 00 00 00 .........OAT....
18 00 00 00 01 9a 07 01 01 00 01 00 01 00 00 00 ................
01 00 01 05 09 01 01 00 00 00 00 00 00 00 00 00 ................
68 00 00 00 01 01 02 00 0d 00 00 00 31 30 2e 35 h...........10.5
30 2e 33 31 2e 31 30 30 00 ac 5f ac 17 00 00 00 0.31.100.._.....
14 01 0f 00 52 53 54 4b 0a 76 58 88 a6 01 00 00 ....RSTK.vX.....
00 00 00 01 00 00 00 00 02 00 00 00 00 00 00 00 ................
08 00 00 00 01 98 07 01 00 4f 41 54 01 00 00 00 .........OAT....
18 00 00 00 01 98 07 01 01 00 01 00 01 00 00 00 ................
01 00 01 05 09 01 01 00 00 00 00 00 ............
2017-01-11 11:34:52.038@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::drain_queue_helper, sending 1 buffers
2017-01-11 11:34:52.038@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::drain_queue_helper, buffer 0/1 has 428 bytes
2017-01-11 11:34:52.039@<local_host>@23677 at LM_DEBUG@TAO - Transport[9]::drain_queue_helper (0/428) - HEXDUMP 428 bytes
47 49 4f 50 01 02 01 00 a0 01 00 00 06 00 00 00 GIOP............
03 00 00 00 00 00 00 00 3a 00 00 00 14 01 0f 00 ........:.......
4e 55 50 00 00 00 1c 00 00 00 00 01 00 00 00 52 NUP............R
6f 6f 74 50 4f 41 00 4e 61 6d 65 53 65 72 76 69 ootPOA.NameServi
63 65 00 00 00 00 00 01 00 00 00 4e 61 6d 65 53 ce.........NameS
65 72 76 69 63 65 5f 32 07 00 00 00 72 65 62 69 ervice_2....rebi
6e 64 00 5f 00 00 00 00 02 00 00 00 0f 00 00 00 nd._............
41 44 61 74 50 33 53 65 72 76 69 63 65 73 00 2f ADatP3Services./
01 00 00 00 00 6d 69 6e 0b 00 00 00 4d 54 46 53 .....min....MTFS
65 72 76 69 63 65 00 3a 01 00 00 00 00 69 6e 67 ervice.:.....ing
22 00 00 00 49 44 4c 3a 49 72 69 73 53 65 72 76 "...IDL:IrisServ
69 63 65 73 2f 4d 74 66 53 65 72 76 69 63 65 5f ices/MtfService_
74 3a 31 2e 30 00 00 00 02 00 00 00 00 00 00 00 t:1.0...........
68 00 00 00 01 01 02 00 0c 00 00 00 31 30 2e 35 h...........10.5
30 2e 33 31 2e 31 36 00 5f ac 00 00 17 00 00 00 0.31.16._.......
14 01 0f 00 52 53 54 4b 0a 76 58 88 a6 01 00 00 ....RSTK.vX.....
00 00 00 01 00 00 00 00 02 00 00 00 00 00 00 00 ................
08 00 00 00 01 9a 07 01 00 4f 41 54 01 00 00 00 .........OAT....
18 00 00 00 01 9a 07 01 01 00 01 00 01 00 00 00 ................
01 00 01 05 09 01 01 00 00 00 00 00 00 00 00 00 ................
68 00 00 00 01 01 02 00 0d 00 00 00 31 30 2e 35 h...........10.5
30 2e 33 31 2e 31 30 30 00 ac 5f ac 17 00 00 00 0.31.100.._.....
14 01 0f 00 52 53 54 4b 0a 76 58 88 a6 01 00 00 ....RSTK.vX.....
00 00 00 01 00 00 00 00 02 00 00 00 00 00 00 00 ................
08 00 00 00 01 98 07 01 00 4f 41 54 01 00 00 00 .........OAT....
18 00 00 00 01 98 07 01 01 00 01 00 01 00 00 00 ................
01 00 01 05 09 01 01 00 00 00 00 00 ............
2017-01-11 11:34:52.046@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::drain_queue_helper, end of data
2017-01-11 11:34:52.046@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::cleanup_queue, byte_count = 428
2017-01-11 11:34:52.047@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::cleanup_queue, after transfer, bc = 0, all_sent = 1, ml = 0
2017-01-11 11:34:52.047@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::drain_queue_helper, byte_count = 428, head_is_empty = 1
2017-01-11 11:34:52.047@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::drain_queue_i, helper retval = 1
2017-01-11 11:34:52.048@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::handle_input
2017-01-11 11:34:52.048@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::process_queue_head, 0 enqueued
2017-01-11 11:34:52.048@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::handle_input_parse_data, enter
2017-01-11 11:34:52.048@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::handle_input_parse_data, read 24 bytes
2017-01-11 11:34:52.048@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - GIOP_Message_State::parse_message_header_i
2017-01-11 11:34:52.049@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - GIOP_Message_State::get_version_info
2017-01-11 11:34:52.049@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - GIOP_Message_State::get_byte_order_info
2017-01-11 11:34:52.049@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::process_parsed_messages, entering (missing data == 0)
2017-01-11 11:34:52.049@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - GIOP_Message_Base::dump_msg, Transport[9] recv GIOP message v1.2, 12 data bytes, my endian, Type Reply[6]
2017-01-11 11:34:52.050@<local_host>@23677 at LM_DEBUG@GIOP message - HEXDUMP 24 bytes
47 49 4f 50 01 02 01 01 0c 00 00 00 06 00 00 00 GIOP............
00 00 00 00 00 00 00 00 ........
2017-01-11 11:34:52.050@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::make_idle
2017-01-11 11:34:52.051@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Cache_IntId_T::recycle_state, ENTRY_BUSY->ENTRY_IDLE_AND_PURGABLE Transport[9] IntId=0x1e1d200
2017-01-11 11:34:52.051@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) SCG:<dtor=0x7ffe091c3620> - new repo=0x1dab0b0
2017-01-11 11:34:52.051@<local_host>@23677 at LM_DEBUG@ADatP3Services| ./src/NamingClient.cpp:28 - Object MTFService. registered in NameService
2017-01-11 11:34:52.052@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - TAO_System_Id_With_Unique_Id_Strategy::bind_using_system_id: type=IDL:LineFormatService/LineFormatServer_t:1.0, id=0x01000000010000
2017-01-11 11:34:52.052@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Stub::base_profiles, acquired profile lock this = 0x1e217a0
2017-01-11 11:34:52.052@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) - SCG:<ctor=0x7ffe091c3620> - config=0x1dab040 repo=0x1dab0b0 superceded by repo=0x1dab0b0
2017-01-11 11:34:52.052@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Invocation_Adapter::invoke_i, making a TAO_CS_REMOTE_STRATEGY invocation
2017-01-11 11:34:52.052@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=TAO_ORB_Core_Static_Resources type=0x1db1a30 => 0x1db17a0
2017-01-11 11:34:52.053@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport_Cache_Manager_T::is_entry_available_i[9], true, state is ENTRY_IDLE_AND_PURGABLE
2017-01-11 11:34:52.053@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Cache_IntId_T::recycle_state, ENTRY_IDLE_AND_PURGABLE->ENTRY_BUSY Transport[9] IntId=0x1e1d200
2017-01-11 11:34:52.053@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport_Cache_Manager_T::find_i, Found available Transport[9] @hash:index {171087029:0}
2017-01-11 11:34:52.053@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport_Connector::connect, got an existing connected Transport[9] in role TAO_CLIENT_ROLE
2017-01-11 11:34:52.054@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Exclusive_TMS::request_id - <7>
2017-01-11 11:34:52.054@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - GIOP_Message_Base::dump_msg, send GIOP message v1.2, 436 data bytes, my endian, Type Request[7]
2017-01-11 11:34:52.054@<local_host>@23677 at LM_DEBUG@GIOP message - HEXDUMP 448 bytes
47 49 4f 50 01 02 01 00 b4 01 00 00 07 00 00 00 GIOP............
03 00 00 00 00 00 00 00 3a 00 00 00 14 01 0f 00 ........:.......
4e 55 50 00 00 00 1c 00 00 00 00 01 00 00 00 52 NUP............R
6f 6f 74 50 4f 41 00 4e 61 6d 65 53 65 72 76 69 ootPOA.NameServi
63 65 00 00 00 00 00 01 00 00 00 4e 61 6d 65 53 ce.........NameS
65 72 76 69 63 65 5f 32 07 00 00 00 72 65 62 69 ervice_2....rebi
6e 64 00 5f 00 00 00 00 02 00 00 00 0f 00 00 00 nd._............
41 44 61 74 50 33 53 65 72 76 69 63 65 73 00 2f ADatP3Services./
01 00 00 00 00 6d 69 6e 12 00 00 00 4c 69 6e 65 .....min....Line
46 6f 72 6d 61 74 53 65 72 76 69 63 65 00 6e 67 FormatService.ng
01 00 00 00 00 44 4c 3a 2d 00 00 00 49 44 4c 3a .....DL:-...IDL:
4c 69 6e 65 46 6f 72 6d 61 74 53 65 72 76 69 63 LineFormatServic
65 2f 4c 69 6e 65 46 6f 72 6d 61 74 53 65 72 76 e/LineFormatServ
65 72 5f 74 3a 31 2e 30 00 00 00 00 02 00 00 00 er_t:1.0........
00 00 00 00 68 00 00 00 01 01 02 00 0c 00 00 00 ....h...........
31 30 2e 35 30 2e 33 31 2e 31 36 00 5f ac 5f ac 10.50.31.16._._.
17 00 00 00 14 01 0f 00 52 53 54 4b 0a 76 58 88 ........RSTK.vX.
a6 01 00 01 00 00 00 01 00 00 00 00 02 00 00 00 ................
00 00 00 00 08 00 00 00 01 98 07 01 00 4f 41 54 .............OAT
01 00 00 00 18 00 00 00 01 98 07 01 01 00 01 00 ................
01 00 00 00 01 00 01 05 09 01 01 00 00 00 00 00 ................
00 00 00 00 68 00 00 00 01 01 02 00 0d 00 00 00 ....h...........
31 30 2e 35 30 2e 33 31 2e 31 30 30 00 ac 5f ac 10.50.31.100.._.
17 00 00 00 14 01 0f 00 52 53 54 4b 0a 76 58 88 ........RSTK.vX.
a6 01 00 01 00 00 00 01 00 00 00 00 02 00 00 00 ................
00 00 00 00 08 00 00 00 01 9e 07 01 00 4f 41 54 .............OAT
01 00 00 00 18 00 00 00 01 98 07 01 01 00 01 00 ................
01 00 00 00 01 00 01 05 09 01 01 00 00 00 00 00 ................
2017-01-11 11:34:52.060@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::drain_queue_helper, sending 1 buffers
2017-01-11 11:34:52.060@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::drain_queue_helper, buffer 0/1 has 448 bytes
2017-01-11 11:34:52.060@<local_host>@23677 at LM_DEBUG@TAO - Transport[9]::drain_queue_helper (0/448) - HEXDUMP 448 bytes
47 49 4f 50 01 02 01 00 b4 01 00 00 07 00 00 00 GIOP............
03 00 00 00 00 00 00 00 3a 00 00 00 14 01 0f 00 ........:.......
4e 55 50 00 00 00 1c 00 00 00 00 01 00 00 00 52 NUP............R
6f 6f 74 50 4f 41 00 4e 61 6d 65 53 65 72 76 69 ootPOA.NameServi
63 65 00 00 00 00 00 01 00 00 00 4e 61 6d 65 53 ce.........NameS
65 72 76 69 63 65 5f 32 07 00 00 00 72 65 62 69 ervice_2....rebi
6e 64 00 5f 00 00 00 00 02 00 00 00 0f 00 00 00 nd._............
41 44 61 74 50 33 53 65 72 76 69 63 65 73 00 2f ADatP3Services./
01 00 00 00 00 6d 69 6e 12 00 00 00 4c 69 6e 65 .....min....Line
46 6f 72 6d 61 74 53 65 72 76 69 63 65 00 6e 67 FormatService.ng
01 00 00 00 00 44 4c 3a 2d 00 00 00 49 44 4c 3a .....DL:-...IDL:
4c 69 6e 65 46 6f 72 6d 61 74 53 65 72 76 69 63 LineFormatServic
65 2f 4c 69 6e 65 46 6f 72 6d 61 74 53 65 72 76 e/LineFormatServ
65 72 5f 74 3a 31 2e 30 00 00 00 00 02 00 00 00 er_t:1.0........
00 00 00 00 68 00 00 00 01 01 02 00 0c 00 00 00 ....h...........
31 30 2e 35 30 2e 33 31 2e 31 36 00 5f ac 5f ac 10.50.31.16._._.
17 00 00 00 14 01 0f 00 52 53 54 4b 0a 76 58 88 ........RSTK.vX.
a6 01 00 01 00 00 00 01 00 00 00 00 02 00 00 00 ................
00 00 00 00 08 00 00 00 01 98 07 01 00 4f 41 54 .............OAT
01 00 00 00 18 00 00 00 01 98 07 01 01 00 01 00 ................
01 00 00 00 01 00 01 05 09 01 01 00 00 00 00 00 ................
00 00 00 00 68 00 00 00 01 01 02 00 0d 00 00 00 ....h...........
31 30 2e 35 30 2e 33 31 2e 31 30 30 00 ac 5f ac 10.50.31.100.._.
17 00 00 00 14 01 0f 00 52 53 54 4b 0a 76 58 88 ........RSTK.vX.
a6 01 00 01 00 00 00 01 00 00 00 00 02 00 00 00 ................
00 00 00 00 08 00 00 00 01 9e 07 01 00 4f 41 54 .............OAT
01 00 00 00 18 00 00 00 01 98 07 01 01 00 01 00 ................
01 00 00 00 01 00 01 05 09 01 01 00 00 00 00 00 ................
2017-01-11 11:34:52.064@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::drain_queue_helper, end of data
2017-01-11 11:34:52.065@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::cleanup_queue, byte_count = 448
2017-01-11 11:34:52.065@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::cleanup_queue, after transfer, bc = 0, all_sent = 1, ml = 0
2017-01-11 11:34:52.065@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::drain_queue_helper, byte_count = 448, head_is_empty = 1
2017-01-11 11:34:52.065@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::drain_queue_i, helper retval = 1
2017-01-11 11:34:52.065@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::handle_input
2017-01-11 11:34:52.065@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::process_queue_head, 0 enqueued
2017-01-11 11:34:52.065@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::handle_input_parse_data, enter
2017-01-11 11:34:52.065@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::handle_input_parse_data, read 24 bytes
2017-01-11 11:34:52.066@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - GIOP_Message_State::parse_message_header_i
2017-01-11 11:34:52.066@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - GIOP_Message_State::get_version_info
2017-01-11 11:34:52.066@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - GIOP_Message_State::get_byte_order_info
2017-01-11 11:34:52.066@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::process_parsed_messages, entering (missing data == 0)
2017-01-11 11:34:52.066@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - GIOP_Message_Base::dump_msg, Transport[9] recv GIOP message v1.2, 12 data bytes, my endian, Type Reply[7]
2017-01-11 11:34:52.066@<local_host>@23677 at LM_DEBUG@GIOP message - HEXDUMP 24 bytes
47 49 4f 50 01 02 01 01 0c 00 00 00 07 00 00 00 GIOP............
00 00 00 00 00 00 00 00 ........
2017-01-11 11:34:52.067@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::make_idle
2017-01-11 11:34:52.067@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Cache_IntId_T::recycle_state, ENTRY_BUSY->ENTRY_IDLE_AND_PURGABLE Transport[9] IntId=0x1e1d200
2017-01-11 11:34:52.067@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) SCG:<dtor=0x7ffe091c3620> - new repo=0x1dab0b0
2017-01-11 11:34:52.067@<local_host>@23677 at LM_DEBUG@ADatP3Services| ./src/NamingClient.cpp:28 - Object LineFormatService. registered in NameService
2017-01-11 11:34:52.068@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) - SCG:<ctor=0x7ffe091c3660> - config=0x1dab040 repo=0x1dab0b0 superceded by repo=0x1dab0b0
2017-01-11 11:34:52.068@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Invocation_Adapter::invoke_i, making a TAO_CS_REMOTE_STRATEGY invocation
2017-01-11 11:34:52.068@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) DSB::instance, repo=0x1dab0b0, name=TAO_ORB_Core_Static_Resources type=0x1db1a30 => 0x1db17a0
2017-01-11 11:34:52.068@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport_Cache_Manager_T::is_entry_available_i[9], true, state is ENTRY_IDLE_AND_PURGABLE
2017-01-11 11:34:52.068@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Cache_IntId_T::recycle_state, ENTRY_IDLE_AND_PURGABLE->ENTRY_BUSY Transport[9] IntId=0x1e1d200
2017-01-11 11:34:52.068@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport_Cache_Manager_T::find_i, Found available Transport[9] @hash:index {171087029:0}
2017-01-11 11:34:52.068@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport_Connector::connect, got an existing connected Transport[9] in role TAO_CLIENT_ROLE
2017-01-11 11:34:52.068@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Exclusive_TMS::request_id - <8>
2017-01-11 11:34:52.069@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - GIOP_Message_Base::dump_msg, send GIOP message v1.2, 130 data bytes, my endian, Type Request[8]
2017-01-11 11:34:52.069@<local_host>@23677 at LM_DEBUG@GIOP message - HEXDUMP 142 bytes
47 49 4f 50 01 02 01 00 82 00 00 00 08 00 00 00 GIOP............
03 00 00 00 00 00 00 00 3a 00 00 00 14 01 0f 00 ........:.......
4e 55 50 00 00 00 1c 00 00 00 00 01 00 00 00 52 NUP............R
6f 6f 74 50 4f 41 00 4e 61 6d 65 53 65 72 76 69 ootPOA.NameServi
63 65 00 00 00 00 00 01 00 00 00 4e 61 6d 65 53 ce.........NameS
65 72 76 69 63 65 5f 32 0c 00 00 00 72 65 73 6f ervice_2....reso
6c 76 65 5f 73 74 72 00 00 00 00 00 0f 00 00 00 lve_str.........
1a 00 00 00 41 44 61 74 50 33 53 65 72 76 69 63 ....ADatP3Servic
65 73 2f 4d 54 46 53 65 72 76 69 63 65 00 es/MTFService.
2017-01-11 11:34:52.070@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::drain_queue_helper, sending 1 buffers
2017-01-11 11:34:52.070@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::drain_queue_helper, buffer 0/1 has 142 bytes
2017-01-11 11:34:52.070@<local_host>@23677 at LM_DEBUG@TAO - Transport[9]::drain_queue_helper (0/142) - HEXDUMP 142 bytes
47 49 4f 50 01 02 01 00 82 00 00 00 08 00 00 00 GIOP............
03 00 00 00 00 00 00 00 3a 00 00 00 14 01 0f 00 ........:.......
4e 55 50 00 00 00 1c 00 00 00 00 01 00 00 00 52 NUP............R
6f 6f 74 50 4f 41 00 4e 61 6d 65 53 65 72 76 69 ootPOA.NameServi
63 65 00 00 00 00 00 01 00 00 00 4e 61 6d 65 53 ce.........NameS
65 72 76 69 63 65 5f 32 0c 00 00 00 72 65 73 6f ervice_2....reso
6c 76 65 5f 73 74 72 00 00 00 00 00 0f 00 00 00 lve_str.........
1a 00 00 00 41 44 61 74 50 33 53 65 72 76 69 63 ....ADatP3Servic
65 73 2f 4d 54 46 53 65 72 76 69 63 65 00 es/MTFService.
2017-01-11 11:34:52.073@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::drain_queue_helper, end of data
2017-01-11 11:34:52.073@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::cleanup_queue, byte_count = 142
2017-01-11 11:34:52.073@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::cleanup_queue, after transfer, bc = 0, all_sent = 1, ml = 0
2017-01-11 11:34:52.074@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::drain_queue_helper, byte_count = 142, head_is_empty = 1
2017-01-11 11:34:52.074@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::drain_queue_i, helper retval = 1
2017-01-11 11:34:52.074@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::handle_input
2017-01-11 11:34:52.074@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::process_queue_head, 0 enqueued
2017-01-11 11:34:52.075@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::handle_input_parse_data, enter
2017-01-11 11:34:52.075@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::handle_input_parse_data, read 292 bytes
2017-01-11 11:34:52.075@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - GIOP_Message_State::parse_message_header_i
2017-01-11 11:34:52.075@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - GIOP_Message_State::get_version_info
2017-01-11 11:34:52.076@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - GIOP_Message_State::get_byte_order_info
2017-01-11 11:34:52.076@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::process_parsed_messages, entering (missing data == 0)
2017-01-11 11:34:52.076@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - GIOP_Message_Base::dump_msg, Transport[9] recv GIOP message v1.2, 280 data bytes, my endian, Type Reply[8]
2017-01-11 11:34:52.077@<local_host>@23677 at LM_DEBUG@GIOP message - HEXDUMP 292 bytes
47 49 4f 50 01 02 01 01 18 01 00 00 08 00 00 00 GIOP............
00 00 00 00 00 00 00 00 22 00 00 00 49 44 4c 3a ........"...IDL:
49 72 69 73 53 65 72 76 69 63 65 73 2f 4d 74 66 IrisServices/Mtf
53 65 72 76 69 63 65 5f 74 3a 31 2e 30 00 74 45 Service_t:1.0.tE
02 00 00 00 00 00 00 00 68 00 00 00 01 01 02 00 ........h.......
0c 00 00 00 31 30 2e 35 30 2e 33 31 2e 31 36 00 ....10.50.31.16.
5f ac 5f ac 17 00 00 00 14 01 0f 00 52 53 54 4b _._.........RSTK
0a 76 58 88 a6 01 00 00 00 00 00 01 00 00 00 50 .vX............P
02 00 00 00 00 00 00 00 08 00 00 00 01 9a 07 01 ................
00 4f 41 54 01 00 00 00 18 00 00 00 01 9a 07 01 .OAT............
01 00 01 00 01 00 00 00 01 00 01 05 09 01 01 00 ................
00 00 00 00 00 00 00 00 68 00 00 00 01 01 02 00 ........h.......
0d 00 00 00 31 30 2e 35 30 2e 33 31 2e 31 30 30 ....10.50.31.100
00 ac 5f ac 17 00 00 00 14 01 0f 00 52 53 54 4b .._.........RSTK
0a 76 58 88 a6 01 00 00 00 00 00 01 00 00 00 50 .vX............P
02 00 00 00 00 00 00 00 08 00 00 00 01 98 07 01 ................
00 4f 41 54 01 00 00 00 18 00 00 00 01 98 07 01 .OAT............
01 00 01 00 01 00 00 00 01 00 01 05 09 01 01 00 ................
00 00 00 00 ....
2017-01-11 11:34:52.082@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Stub::base_profiles, acquired profile lock this = 0x1e22930
2017-01-11 11:34:52.082@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[9]::make_idle
2017-01-11 11:34:52.082@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Cache_IntId_T::recycle_state, ENTRY_BUSY->ENTRY_IDLE_AND_PURGABLE Transport[9] IntId=0x1e1d200
2017-01-11 11:34:52.083@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) SCG:<dtor=0x7ffe091c3660> - new repo=0x1dab0b0
2017-01-11 11:34:52.083@<local_host>@23677 at LM_DEBUG@ADatP3Services| ./src/NamingClientExt.cpp:151 - Object ADatP3Services/MTFService found in NameService
2017-01-11 11:34:52.083@<local_host>@23677 at LM_DEBUG@ADatP3Services|MtfServiceWrapper.cc:114 Connect to Server:ADatP3Services/MTFService
2017-01-11 11:34:52.083@<local_host>@23677 at LM_DEBUG@ADatP3Services|./serverImpl/LineFormatServer.cpp:36 11:34:52.083955 LineFormatServer::init_Wrapper
2017-01-11 11:34:52.084@<local_host>@23677 at LM_DEBUG@ADatP3Services| ./src/ServiceDriver.cpp:60 - Starting ORB loop ...
2017-01-11 11:34:52.084@<local_host>@23677 at LM_DEBUG@ADatP3Services|ACE (23677|1) - SCG:<ctor=0x7ffe091c3930> - config=0x1dab040 repo=0x1dab0b0 superceded by repo=0x1dab0b0
[Client connect to Server after short time]
2017-01-11 11:37:17.048@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport_Cache_Manager_T::fill_set_i, current_size = 2, cache_maximum = 32768
2017-01-11 11:37:17.049@<local_host>@23677 at LM_INFO@ADatP3Services|TAO (23677|1) - Transport_Cache_Manager_T::purge, Cache size after purging is [2]
2017-01-11 11:37:17.049@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - TAO_LF_CH_Event[0]::state_changed_i, state LFS_IDLE->LFS_CONNECTION_WAIT
2017-01-11 11:37:17.049@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - IIOP_Connection_Handler[31596560]::IIOP_Connection_Handler, this=0x1e21900
2017-01-11 11:37:17.049@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Concurrency_Strategy::activate_svc_handler, opened as TAO_SERVER_ROLE
2017-01-11 11:37:17.049@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - IIOP_Connection_Handler::open, The local addr is <10.50.31.16:44127>
2017-01-11 11:37:17.049@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - IIOP_Connection_Handler::open, IIOP connection to peer <10.50.31.11:50391> on 28
2017-01-11 11:37:17.049@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport::post_open, tport id changed from 31596560 to 28
2017-01-11 11:37:17.049@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[28]::post_open, cache_map_entry_ is 0
2017-01-11 11:37:17.049@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - TAO_LF_CH_Event[28]::state_changed_i, state LFS_CONNECTION_WAIT->LFS_SUCCESS
2017-01-11 11:37:17.049@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Cache_IntId_T::Cache_IntId_T, this=0x7ffe091c3490 Transport[28] is connected
2017-01-11 11:37:17.049@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Cache_IntId_T::recycle_state, ENTRY_UNKNOWN->ENTRY_IDLE_AND_PURGABLE Transport[28] IntId=0x7ffe091c3490
2017-01-11 11:37:17.049@<local_host>@23677 at LM_INFO@ADatP3Services|TAO (23677|1) - Transport_Cache_Manager_T::bind_i, Transport[28] @ hash:index{171107298:0}
2017-01-11 11:37:17.050@<local_host>@23677 at LM_INFO@ADatP3Services|TAO (23677|1) - Transport_Cache_Manager_T::bind_i: Success Transport[28] @ hash:index{171107298:0}. Cache size is [3]
2017-01-11 11:37:17.050@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[28]::register_handler
2017-01-11 11:37:17.050@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[28]::register_handler - registering event handler with reactor
2017-01-11 11:37:17.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Connection_Handler[28]::handle_input_internal, handle = 28/28
2017-01-11 11:37:17.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[28]::handle_input
2017-01-11 11:37:17.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[28]::process_queue_head, 0 enqueued
2017-01-11 11:37:17.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[28]::handle_input_parse_data, enter
2017-01-11 11:37:17.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[28]::handle_input_parse_data, read 656 bytes
2017-01-11 11:37:17.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - GIOP_Message_State::parse_message_header_i
2017-01-11 11:37:17.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - GIOP_Message_State::get_version_info
2017-01-11 11:37:17.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - GIOP_Message_State::get_byte_order_info
2017-01-11 11:37:17.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[28]::process_parsed_messages, entering (missing data == 0)
2017-01-11 11:37:17.117@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - GIOP_Message_Base::dump_msg, Transport[28] recv GIOP message v1.2, 644 data bytes, my endian, Type Request[1]
2017-01-11 11:37:17.117@<local_host>@23677 at LM_DEBUG@GIOP message - HEXDUMP 656 bytes
47 49 4f 50 01 02 01 00 84 02 00 00 01 00 00 00 GIOP............
03 00 00 00 00 00 00 00 17 00 00 00 14 01 0f 00 ................
52 53 54 4b 0a 76 58 88 a6 01 00 00 00 00 00 01 RSTK.vX.........
00 00 00 04 08 00 00 00 78 6d 6c 32 6d 74 66 00 ........xml2mtf.
01 00 00 00 01 00 00 00 0c 00 00 00 01 bb c2 b4 ................
01 00 01 00 09 01 01 00 30 02 00 00 3c 3f 78 6d ........0...<?xm
6c 20 76 65 72 73 69 6f 6e 3d 22 31 2e 30 22 20 l version="1.0"
65 6e 63 6f 64 69 6e 67 3d 22 55 54 46 2d 38 22 encoding="UTF-8"
3f 3e 0a 3c 6d 74 66 3a 6d 6f 76 65 6d 65 6e 74 ?>.<mtf:movement
5f 63 6f 6d 70 6c 65 74 69 6f 6e 5f 72 65 70 6f _completion_repo
72 74 20 6d 74 66 69 64 3d 22 4d 43 52 22 20 78 rt mtfid="MCR" x
6d 6c 6e 73 3a 6d 74 66 3d 22 72 64 6f 2e 70 69 mlns:mtf="rdo.pi
74 72 61 64 77 61 72 2e 63 6f 6d 22 20 78 6d 6c tradwar.com" xml
6e 73 3a 78 73 69 3d 22 68 74 74 70 3a 2f 2f 77 ns:xsi="http://w
77 77 2e 77 33 2e 6f 72 67 2f 32 30 30 31 2f 58 ww.w3.org/2001/X
4d 4c 53 63 68 65 6d 61 2d 69 6e 73 74 61 6e 63 MLSchema-instanc
65 22 3e 0a 3c 6d 65 74 61 69 6e 66 6f 3e 0a 09 e">.<metainfo>..
3c 6e 69 63 6b 6e 61 6d 65 3e 53 59 4d 55 4c 41 <nickname>SYMULA
54 4f 52 3c 2f 6e 69 63 6b 6e 61 6d 65 3e 0a 09 TOR</nickname>..
3c 6f 72 69 67 69 6e 61 74 6f 72 3e 50 4c 46 31 <originator>PLF1
31 31 31 3c 2f 6f 72 69 67 69 6e 61 74 6f 72 3e 111</originator>
0a 09 3c 75 69 63 3e 50 4c 46 31 31 31 31 3c 2f ..<uic>PLF1111</
75 69 63 3e 0a 3c 2f 6d 65 74 61 69 6e 66 6f 3e uic>.</metainfo>
0a 3c 6d 74 66 5f 63 6f 6e 74 65 6e 74 3e 45 58 .<mtf_content>EX
45 52 2f 53 59 4d 55 4c 41 54 4f 52 2f 2f 0a 4d ER/SYMULATOR//.M
53 47 49 44 2f 4d 43 52 2f 50 4c 46 31 31 31 31 SGID/MCR/PLF1111
2f 2f 0a 47 45 4f 44 41 54 55 4d 2f 57 38 34 2f //.GEODATUM/W84/
2f 0a 4d 43 52 2f 55 49 43 3a 50 4c 46 31 31 31 /.MCR/UIC:PLF111
31 2f 33 30 30 39 33 34 5a 4e 4f 56 2f 4c 41 54 1/300934ZNOV/LAT
3a 35 31 33 31 2e 32 38 33 4e 30 2d 30 32 31 30 :5131.283N0-0210
36 2e 31 35 30 45 30 2f 2f 0a 0a 3c 2f 6d 74 66 6.150E0//..</mtf
5f 63 6f 6e 74 65 6e 74 3e 0a 3c 70 72 6f 74 6f _content>.<proto
62 75 66 5f 63 6f 6e 74 65 6e 74 3e 0a 43 67 64 buf_content>.Cgd
51 54 45 59 78 4d 54 45 78 45 69 49 4a 64 6d 43 QTEYxMTExEiIJdmC
6f 6a 6d 54 47 37 44 38 52 75 58 44 6a 4b 46 79 ojmTG7D8RuXDjKFy
53 31 7a 38 5a 41 41 41 41 41 41 41 41 41 41 41 S1z8ZAAAAAAAAAAA
69 42 56 64 48 55 7a 67 30 47 67 59 49 69 4c 72 iBVdHUzg0GgYIiLr
36 77 51 55 3d 0a 3c 2f 70 72 6f 74 6f 62 75 66 6wQU=.</protobuf
5f 63 6f 6e 74 65 6e 74 3e 0a 3c 2f 6d 74 66 3a _content>.</mtf:
6d 6f 76 65 6d 65 6e 74 5f 63 6f 6d 70 6c 65 74 movement_complet
69 6f 6e 5f 72 65 70 6f 72 74 3e 0a 01 00 00 00 ion_report>.....
2017-01-11 11:37:17.127@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Codeset_Manager_i::process_service_context, using tcsc <ISO8859_1> (00010001), tcsw <UTF-16> (00010109)
2017-01-11 11:37:17.128@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - GIOP_Message_Base::dump_msg, send GIOP message v1.2, 125 data bytes, my endian, Type Reply[1]
2017-01-11 11:37:17.128@<local_host>@23677 at LM_DEBUG@GIOP message - HEXDUMP 137 bytes
47 49 4f 50 01 02 01 01 7d 00 00 00 01 00 00 00 GIOP....}.......
00 00 00 00 00 00 00 00 6d 00 00 00 45 58 45 52 ........m...EXER
2f 53 59 4d 55 4c 41 54 4f 52 2f 2f 0a 4d 53 47 /SYMULATOR//.MSG
49 44 2f 4d 43 52 2f 50 4c 46 31 31 31 31 2f 2f ID/MCR/PLF1111//
0a 47 45 4f 44 41 54 55 4d 2f 57 38 34 2f 2f 0a .GEODATUM/W84//.
4d 43 52 2f 55 49 43 3a 50 4c 46 31 31 31 31 2f MCR/UIC:PLF1111/
33 30 30 39 33 34 5a 4e 4f 56 2f 4c 41 54 3a 35 300934ZNOV/LAT:5
31 33 31 2e 32 38 33 4e 30 2d 30 32 31 30 36 2e 131.283N0-02106.
31 35 30 45 30 2f 2f 0a 0a 150E0//..
2017-01-11 11:37:17.131@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[28]::drain_queue_helper, sending 1 buffers
2017-01-11 11:37:17.131@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[28]::drain_queue_helper, buffer 0/1 has 137 bytes
2017-01-11 11:37:17.131@<local_host>@23677 at LM_DEBUG@TAO - Transport[28]::drain_queue_helper (0/137) - HEXDUMP 137 bytes
47 49 4f 50 01 02 01 01 7d 00 00 00 01 00 00 00 GIOP....}.......
00 00 00 00 00 00 00 00 6d 00 00 00 45 58 45 52 ........m...EXER
2f 53 59 4d 55 4c 41 54 4f 52 2f 2f 0a 4d 53 47 /SYMULATOR//.MSG
49 44 2f 4d 43 52 2f 50 4c 46 31 31 31 31 2f 2f ID/MCR/PLF1111//
0a 47 45 4f 44 41 54 55 4d 2f 57 38 34 2f 2f 0a .GEODATUM/W84//.
4d 43 52 2f 55 49 43 3a 50 4c 46 31 31 31 31 2f MCR/UIC:PLF1111/
33 30 30 39 33 34 5a 4e 4f 56 2f 4c 41 54 3a 35 300934ZNOV/LAT:5
31 33 31 2e 32 38 33 4e 30 2d 30 32 31 30 36 2e 131.283N0-02106.
31 35 30 45 30 2f 2f 0a 0a 150E0//..
2017-01-11 11:37:17.134@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[28]::drain_queue_helper, end of data
2017-01-11 11:37:17.134@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[28]::cleanup_queue, byte_count = 137
2017-01-11 11:37:17.134@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[28]::cleanup_queue, after transfer, bc = 0, all_sent = 1, ml = 0
2017-01-11 11:37:17.135@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[28]::drain_queue_helper, byte_count = 137, head_is_empty = 1
2017-01-11 11:37:17.135@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[28]::drain_queue_i, helper retval = 1
2017-01-11 11:37:17.135@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Connection_Handler[28]::handle_input_internal, handle = 28/28, retval = 0
2017-01-11 11:38:25.138@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Connection_Handler[28]::handle_input_internal, handle = 28/28
2017-01-11 11:38:25.138@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[28]::handle_input
2017-01-11 11:38:25.138@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[28]::process_queue_head, 0 enqueued
2017-01-11 11:38:25.138@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[28]::handle_input_parse_data, enter
2017-01-11 11:38:25.138@<local_host>@23677 at LM_ERROR@ADatP3Services|TAO (23677|1) - Transport[28]::handle_input, error parsing incoming message
2017-01-11 11:38:25.138@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Resume_Handle::handle_input_return_value_hook, handle_input returning -1, so handle is not resumed.
2017-01-11 11:38:25.138@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Connection_Handler[28]::handle_input_internal, handle = 28/28, retval = -1
2017-01-11 11:38:25.138@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Connection_Handler[28]::close_connection_eh, purging entry from cache
2017-01-11 11:38:25.138@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[28]::pre_close
2017-01-11 11:38:25.138@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport_Cache_Manager_T::mark_connected, false Transport[28]
2017-01-11 11:38:25.138@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[28]::purge_entry, entry is 0x1e1e570
2017-01-11 11:38:25.138@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[28]::cleanup_queue_i, cleaning up complete queue
2017-01-11 11:38:25.138@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[28]::cleanup_queue_i, discarded 0 messages, 0 bytes.
2017-01-11 11:38:25.138@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Connection_Handler[28]::close_connection_eh, removing from the reactor
2017-01-11 11:38:25.139@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Connection_Handler[28]::close_connection_eh, cancel all timers
2017-01-11 11:38:25.139@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[28]::cleanup_queue_i, cleaning up complete queue
2017-01-11 11:38:25.139@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[28]::cleanup_queue_i, discarded 0 messages, 0 bytes.
2017-01-11 11:38:25.140@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - TAO_LF_CH_Event[28]::state_changed_i, state LFS_SUCCESS->LFS_CONNECTION_CLOSED
2017-01-11 11:38:25.140@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Connection_Handler[28]::close_connection_eh end
2017-01-11 11:38:25.140@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - IIOP_Connection_Handler[28]::~IIOP_Connection_Handler, this=0x1e21900, transport=0x1e22010
2017-01-11 11:38:25.140@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[28]::~Transport
2017-01-11 11:38:25.141@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[28]::cleanup_queue_i, cleaning up complete queue
2017-01-11 11:38:25.141@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[28]::cleanup_queue_i, discarded 0 messages, 0 bytes.
[Client is connecting after some time]
2017-01-11 15:49:37.851@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport_Cache_Manager_T::fill_set_i, current_size = 2, cache_maximum = 32768
2017-01-11 15:49:37.851@<local_host>@23677 at LM_INFO@ADatP3Services|TAO (23677|1) - Transport_Cache_Manager_T::purge, Cache size after purging is [2]
2017-01-11 15:49:37.851@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - TAO_LF_CH_Event[0]::state_changed_i, state LFS_IDLE->LFS_CONNECTION_WAIT
2017-01-11 15:49:37.851@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - IIOP_Connection_Handler[31596560]::IIOP_Connection_Handler, this=0x1e20ec0
2017-01-11 15:49:37.851@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Concurrency_Strategy::activate_svc_handler, opened as TAO_SERVER_ROLE
2017-01-11 15:49:37.851@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - IIOP_Connection_Handler::open, The local addr is <10.50.31.16:44127>
2017-01-11 15:49:37.851@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - IIOP_Connection_Handler::open, IIOP connection to peer <10.50.31.11:56243> on 1542
2017-01-11 15:49:37.851@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport::post_open, tport id changed from 31596560 to 1542
2017-01-11 15:49:37.851@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[1542]::post_open, cache_map_entry_ is 0
2017-01-11 15:49:37.852@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - TAO_LF_CH_Event[1542]::state_changed_i, state LFS_CONNECTION_WAIT->LFS_SUCCESS
2017-01-11 15:49:37.852@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Cache_IntId_T::Cache_IntId_T, this=0x7ffe091c3490 Transport[1542] is connected
2017-01-11 15:49:37.852@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Cache_IntId_T::recycle_state, ENTRY_UNKNOWN->ENTRY_IDLE_AND_PURGABLE Transport[1542] IntId=0x7ffe091c3490
2017-01-11 15:49:37.852@<local_host>@23677 at LM_INFO@ADatP3Services|TAO (23677|1) - Transport_Cache_Manager_T::bind_i, Transport[1542] @ hash:index{171113150:0}
2017-01-11 15:49:37.852@<local_host>@23677 at LM_INFO@ADatP3Services|TAO (23677|1) - Transport_Cache_Manager_T::bind_i: Success Transport[1542] @ hash:index{171113150:0}. Cache size is [3]
2017-01-11 15:49:37.852@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[1542]::register_handler
2017-01-11 15:49:37.852@<local_host>@23677 at LM_DEBUG@ADatP3Services|TAO (23677|1) - Transport[1542]::register_handler - registering event handler with reactor
[Now Server hangs and use 100% of the processor, and the client is blocked]
Best Regards,
Daniel Suchodolski
PIT-RADWAR S.A.
z siedzibą w Warszawie
ul.Poligonowa 30
Wpisana do Rejestru Przedsiębiorców Krajowego Rejestru Sądowego
przez Sąd Rejonowy dla m.st. Warszawy w Warszawie, XIII Wydział Gospodarczy.
NR KRS 0000297470
NIP: 525 000 9298
Kapitał zakładowy 421 542 770,00 PLN w całości wpłacony.
www.pitradwar.com
Niniejsza wiadomość jest przeznaczona wyłącznie dla wskazanego w niej adresata i stanowi własność PIT-RADWAR S.A. Jeżeli nie jesteście Państwo adresatem tej wiadomości, bądź otrzymaliście ją przez pomyłkę, informujemy, że jej rozpowszechnianie lub kopiowanie oraz jakiekolwiek czynienie z niej użytku jest zakazane i może naruszać prawo. Prosimy o powiadomienie o powyższym nadawcy i trwałe usunięcie wiadomości wraz z załącznikami.
P Zanim wydrukujesz, pomyśl o środowisku
This message is intended only for use of the named addressee and is the property of PIT-RADWAR S.A. If you are not the receiver of this e-mail or you have received it accidentally we inform that publishing or copying this message or any other using it is forbidden and may break the law. We kindly ask to inform the sender of the message about what is mentioned above and to remove the message permanently from your computer together with attached files.
P Please consider the environment before printing this email
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://list.isis.vanderbilt.edu/pipermail/tao-bugs/attachments/20170112/bb9a11b2/attachment-0001.html>
More information about the tao-bugs
mailing list