TAO/tests/ZIOP/README.txt

   1
   2
   3 This test is for ZIOP. Below the output when running this with -debug,
   4 most important, check the hex dumps to see that the application data
   5 is compressed
   6
   7 [build@balrog ZIOP]$ perl run_test.pl -debug
   8 TAO (3199|46912520506160) Completed initializing the process-wide service context
   9 TAO (3199|46912520506160) Default ORB services initialization begins
  10 TAO (3199|46912520506160) - ORBInitializer_Registry::register_orb_initializer 0 @0x17b2fe60
  11 TAO (3199|46912520506160) - ORBInitializer_Registry::register_orb_initializer 1 @0x17b2e660
  12 TAO (3199|46912520506160) Default ORB services initialization completed
  13 TAO (3199|46912520506160) We are the default ORB ...
  14 TAO (3199|46912520506160) Initializing the orb-specific services
  15 TAO (3199|46912520506160) Default_Resource_Factory - codeset manager=0x17b422a0
  16 TAO (3199|46912520506160) - Codeset_Manager_i::init_ccs, Loaded Codeset translator <UTF8_Latin1_Factory>, ncs = 00010001 tcs = 05010001
  17 TAO (3199|46912520506160) - UTF16_BOM_Translator: forceBE 0
  18 TAO (3199|46912520506160) Loaded default protocol <IIOP_Factory>
  19 TAO (3199|46912520506160) created new ORB <>
  20 TAO (3199|46912520506160) - IIOP_Acceptor::open_i, listening on: <balrog.remedy.nl:44773>
  21 TAO (3199|46912520506160) - IIOP_Acceptor::open_i, listening on: <192.168.248.1:44773>
  22 TAO (3199|46912520506160) - IIOP_Acceptor::open_i, listening on: <192.168.238.1:44773>
  23 TAO (3199|46912520506160) - ORB_Core::run, start [run]
  24 TAO (3199|46912520506160) - ORB_Core::run, calling handle_events()
  25 TAO (3201|46912517403856) Completed initializing the process-wide service context
  26 TAO (3201|46912517403856) Default ORB services initialization begins
  27 TAO (3201|46912517403856) - ORBInitializer_Registry::register_orb_initializer 0 @0x8d527f0
  28 TAO (3201|46912517403856) - ORBInitializer_Registry::register_orb_initializer 1 @0x8d50ff0
  29 TAO (3201|46912517403856) Default ORB services initialization completed
  30 TAO (3201|46912517403856) We are the default ORB ...
  31 TAO (3201|46912517403856) Initializing the orb-specific services
  32 TAO (3201|46912517403856) Default_Resource_Factory - codeset manager=0x8d64c20
  33 TAO (3201|46912517403856) - Codeset_Manager_i::init_ccs, Loaded Codeset translator <UTF8_Latin1_Factory>, ncs = 00010001 tcs = 05010001
  34 TAO (3201|46912517403856) - UTF16_BOM_Translator: forceBE 0
  35 TAO (3201|46912517403856) Loaded default protocol <IIOP_Factory>
  36 TAO (3201|46912517403856) created new ORB <>
  37 TAO (3201|46912517403856) - Transport_Cache_Manager::fill_set_i: current_size = 0, cache_maximum = 512
  38 TAO (3201|46912517403856) - Transport_Cache_Manager::purge: Cache size after purging is [0]
  39 TAO (3201|46912517403856) - IIOP_Connector::begin_connection, to <balrog.remedy.nl:44773> which should block
  40 TAO (3201|46912517403856) - Transport_Connector::wait_for_connection_completion, going to wait for connection completion on transport[148297632]
  41 TAO (3201|46912517403856) - Leader_Follower[148297632]::wait_for_event, (leader) enter reactor event loop
  42 TAO (3199|46912520506160) - Transport_Cache_Manager::fill_set_i: current_size = 0, cache_maximum = 512
  43 TAO (3201|46912517403856) - IIOP_Connection_Handler::open, The local addr is <127.0.0.1:53936>
  44 TAO (3199|46912520506160) - Transport_Cache_Manager::purge: Cache size after purging is [0]
  45 TAO (3201|46912517403856) - IIOP_Connection_Handler::open, IIOP connection to peer <127.0.0.1:44773> on 5
  46 TAO (3201|46912517403856) - Leader_Follower[148297632]::wait_for_event, (leader) exit reactor event loop
  47 TAO (3201|46912517403856) - Transport_Connector::wait_for_connection_completion, transport [5], wait done result = 1
  48 TAO (3201|46912517403856) - IIOP_Connector::make_connection, new connected connection to <balrog.remedy.nl:44773> on Transport[5]
  49 TAO (3201|46912517403856) - Transport_Cache_Manager::bind_i: Transport[5] @ hash:index{2130751206:0}
  50 TAO (3201|46912517403856) - Transport_Cache_Manager::bind_i: Success Transport[5] @ hash:index{2130751206:0}. Cache size is [1]
  51 TAO (3201|46912517403856) - Transport[5]::register_handler
  52 TAO (3201|46912517403856) - Transport_Connector::connect, opening Transport[5] in TAO_CLIENT_ROLE
  53 TAO (3199|46912520506160) - Concurrency_Strategy::activate_svc_handler, opened as TAO_SERVER_ROLE
  54 TAO (3201|46912517403856) - Transport_Cache_Manager::is_entry_available:returns true state is [1]
  55 TAO (3201|46912517403856) - Transport_Cache_Manager::find_i: Found available Transport[5] @hash:index {2130751206:0}
  56 TAO (3201|46912517403856) - Transport_Connector::connect, got an existing connected Transport[5] in role TAO_CLIENT_ROLE
  57 TAO (3201|46912517403856) - Codeset_Manager_i::set_tcs, setting char translator (00010001)
  58 TAO (3199|46912520506160) - IIOP_Connection_Handler::open, The local addr is <127.0.0.1:44773>
  59 TAO (3201|46912517403856) - Codeset_Manager_i::set_tcs, setting wchar translator (00010109)
  60 TAO (3199|46912520506160) - IIOP_Connection_Handler::open, IIOP connection to peer <127.0.0.1:53936> on 8
  61 TAO (3201|46912517403856) - Muxed_TMS[5]::request_id, <1>
  62 TAO (3201|46912517403856) - Codeset_Manager_i::generate_service_context, using tcs_c = 00010001, tcs_w = 00010109
  63 TAO (3199|46912520506160) - Transport_Cache_Manager::bind_i: Transport[8] @ hash:index{2130760369:0}
  64 TAO (3199|46912520506160) - Transport_Cache_Manager::bind_i: Success Transport[8] @ hash:index{2130760369:0}. Cache size is [1]
  65 TAO (3199|46912520506160) - Transport[8]::register_handler
  66 TAO (3199|46912520506160) - ORB_Core::run, handle_events() returns 1
  67 TAO (3199|46912520506160) - ORB_Core::run, calling handle_events()
  68 TAO (3201|46912517403856) - GIOP_Message_Base::dump_msg, send GIOP v1.2 msg, 140 data bytes, my endian, is compressed, Type Request[1]
  69 GIOP message - HEXDUMP 152 bytes
  70 47 49 4f 50 01 02 05 00  8c 00 00 00 01 00 00 00   GIOP............
  71 03 00 00 00 00 00 00 00  23 00 00 00 14 01 0f 00   ........#.......
  72 4e 53 54 66 db 85 48 3c  ff 0a 00 01 00 00 00 01   NSTf..H<........
  73 00 00 00 00 00 00 00 01  00 00 00 01 00 00 00 00   ................
  74 0b 00 00 00 67 65 74 5f  73 74 72 69 6e 67 00 00   ....get_string..
  75 01 00 00 00 01 00 00 00  0c 00 00 00 01 74 63 73   .............tcs
  76 01 00 01 00 09 01 01 00  04 00 00 00 c3 00 00 00   ................
  77 24 00 00 00 78 da db cf  c0 c0 10 92 91 59 ac 00   $...x........Y..
  78 44 89 0a 25 a9 c5 25 0a  c5 25 45 99 79 e9 43 45   D..%..%..%E.y.CE
  79 90 8b 01 00 a0 b6 45 d6                            ......E.
  80 TAO (3199|46912520506160) - Connection_Handler[8]::handle_input, handle = 8/8
  81 TAO (3201|46912517403856) - Transport[5]::cleanup_queue, byte_count = 152
  82 TAO (3199|46912520506160) - Transport[8]::handle_input
  83 TAO (3201|46912517403856) - Transport[5]::cleanup_queue, after transfer, bc = 0, all_sent = 1, ml = 0
  84 TAO (3199|46912520506160) - Transport[8]::process_queue_head, 0 enqueued
  85 TAO (3201|46912517403856) - Transport[5]::drain_queue_helper, byte_count = 152, head_is_empty = 1
  86 TAO (3199|46912520506160) - Transport[8]::handle_input_parse_data, enter
  87 TAO (3201|46912517403856) - Transport[5]::drain_queue_i, helper retval = 1
  88 TAO (3201|46912517403856) - Transport[5]::make_idle
  89 TAO (3201|46912517403856) - Leader_Follower[5]::wait_for_event, (leader) enter reactor event loop
  90 TAO (3199|46912520506160) - Transport[8]::handle_input_parse_data, read 152 bytes
  91 TAO (3199|46912520506160) - GIOP_Message_State::parse_message_header_i
  92 TAO (3199|46912520506160) - GIOP_Message_State::get_version_info
  93 TAO (3199|46912520506160) - GIOP_Message_State::get_byte_order_info
  94 TAO (3199|46912520506160) - Transport[8]::process_parsed_messages, entering (missing data == 0)
  95 TAO (3199|46912520506160) - GIOP_Message_Base::dump_msg, recv GIOP v1.2 msg, 140 data bytes, my endian, is compressed, Type Request[1]
  96 GIOP message - HEXDUMP 152 bytes
  97 47 49 4f 50 01 02 05 00  8c 00 00 00 01 00 00 00   GIOP............
  98 03 00 00 00 00 00 00 00  23 00 00 00 14 01 0f 00   ........#.......
  99 4e 53 54 66 db 85 48 3c  ff 0a 00 01 00 00 00 01   NSTf..H<........
 100 00 00 00 00 00 00 00 01  00 00 00 01 00 00 00 00   ................
 101 0b 00 00 00 67 65 74 5f  73 74 72 69 6e 67 00 00   ....get_string..
 102 01 00 00 00 01 00 00 00  0c 00 00 00 01 74 63 73   .............tcs
 103 01 00 01 00 09 01 01 00  04 00 00 00 c3 00 00 00   ................
 104 24 00 00 00 78 da db cf  c0 c0 10 92 91 59 ac 00   $...x........Y..
 105 44 89 0a 25 a9 c5 25 0a  c5 25 45 99 79 e9 43 45   D..%..%..%E.y.CE
 106 90 8b 01 00 a0 b6 45 d6                            ......E.
 107 TAO (3199|46912520506160) - Codeset_Manager_i::process_service_context, using tcsc = 00010001, tcsw = 00010109
 108 Received <This is a test stringThis is a test stringThis is a test stringThis is a test stringThis is a test stringThis is a test stringThis is a test stringThis is a test stringThis is a test string
 109 >
 110 TAO (3199|46912520506160) - GIOP_Message_Base::dump_msg, send GIOP v1.2 msg, 52 data bytes, my endian, is compressed, Type Reply[1]
 111 GIOP message - HEXDUMP 64 bytes
 112 47 49 4f 50 01 02 05 01  34 00 00 00 01 00 00 00   GIOP....4.......
 113 00 00 00 00 00 00 00 00  04 00 00 00 72 00 00 00   ............r...
 114 1c 00 00 00 78 9c cb 63  60 60 f0 48 cd c9 c9 57   ....x..c``.H...W
 115 28 c9 48 2d 4a 55 a4 35  9b 8b 01 00 c3 3c 27 2e   (.H-JU.5.....<'.
 116 TAO (3199|46912520506160) - Transport[8]::cleanup_queue, byte_count = 64
 117 TAO (3199|46912520506160) - Transport[8]::cleanup_queue, after transfer, bc = 0, all_sent = 1, ml = 0
 118 TAO (3201|46912517403856) - Connection_Handler[5]::handle_input, handle = 5/5
 119 TAO (3201|46912517403856) - Transport[5]::handle_input
 120 TAO (3201|46912517403856) - Transport[5]::process_queue_head, 0 enqueued
 121 TAO (3201|46912517403856) - Transport[5]::handle_input_parse_data, enter
 122 TAO (3201|46912517403856) - Transport[5]::handle_input_parse_data, read 64 bytes
 123 TAO (3201|46912517403856) - GIOP_Message_State::parse_message_header_i
 124 TAO (3201|46912517403856) - GIOP_Message_State::get_version_info
 125 TAO (3201|46912517403856) - GIOP_Message_State::get_byte_order_info
 126 TAO (3201|46912517403856) - Transport[5]::process_parsed_messages, entering (missing data == 0)
 127 TAO (3201|46912517403856) - GIOP_Message_Base::dump_msg, recv GIOP v1.2 msg, 52 data bytes, my endian, is compressed, Type Reply[1]
 128 GIOP message - HEXDUMP 64 bytes
 129 47 49 4f 50 01 02 05 01  34 00 00 00 01 00 00 00   GIOP....4.......
 130 00 00 00 00 00 00 00 00  04 00 00 00 72 00 00 00   ............r...
 131 1c 00 00 00 78 9c cb 63  60 60 f0 48 cd c9 c9 57   ....x..c``.H...W
 132 28 c9 48 2d 4a 55 a4 35  9b 8b 01 00 c3 3c 27 2e   (.H-JU.5.....<'.
 133 TAO (3201|46912517403856) - TAO_Muxed_TMS::dispatch_reply, id = 1
 134 TAO (3201|46912517403856) - Connection_Handler[5]::handle_input, handle = 5/5, retval = 0
 135 TAO (3201|46912517403856) - Leader_Follower[5]::wait_for_event, (leader) exit reactor event loop
 136 (3201|46912517403856) - string returned <r>
 137 TAO (3201|46912517403856) - Transport_Cache_Manager::is_entry_available:returns true state is [0]
 138 TAO (3201|46912517403856) - Transport_Cache_Manager::find_i: Found available Transport[5] @hash:index {2130751206:0}
 139 TAO (3201|46912517403856) - Transport_Connector::connect, got an existing connected Transport[5] in role TAO_CLIENT_ROLE
 140 TAO (3201|46912517403856) - Muxed_TMS[5]::request_id, <2>
 141 TAO (3201|46912517403856) - GIOP_Message_Base::dump_msg, send GIOP v1.2 msg, 72 data bytes, my endian, not compressed, Type Request[2]
 142 GIOP message - HEXDUMP 84 bytes
 143 47 49 4f 50 01 02 01 00  48 00 00 00 02 00 00 00   GIOP....H.......
 144 00 00 00 00 00 00 00 00  23 00 00 00 14 01 0f 00   ........#.......
 145 4e 53 54 66 db 85 48 3c  ff 0a 00 01 00 00 00 01   NSTf..H<........
 146 00 00 00 00 00 00 00 01  00 00 00 01 00 00 00 00   ................
 147 09 00 00 00 73 68 75 74  64 6f 77 6e 00 67 00 00   ....shutdown.g..
 148 00 00 00 00                                        ....
 149 TAO (3201|46912517403856) - Transport[5]::send_asynchronous_message_i, trying to send the message (ml = 84)
 150 TAO (3201|46912517403856) - Transport[5]::cleanup_queue, byte_count = 84
 151 TAO (3201|46912517403856) - Transport[5]::cleanup_queue, after transfer, bc = 0, all_sent = 1, ml = 0
 152 TAO (3201|46912517403856) - Transport[5]::drain_queue_helper, byte_count = 84, head_is_empty = 1
 153 TAO (3201|46912517403856) - Transport[5]::drain_queue_i, helper retval = 1
 154 TAO (3201|46912517403856) - Transport[5]::make_idle
 155 CORBA::ORB::destroy() called on ORB <>.
 156 TAO (3199|46912520506160) - Transport[8]::drain_queue_helper, byte_count = 64, head_is_empty = 1
 157 TAO (3199|46912520506160) - Transport[8]::drain_queue_i, helper retval = 1
 158 TAO (3199|46912520506160) - Connection_Handler[8]::handle_input, handle = 8/8, retval = 0
 159 Destroying ORB <>
 160 TAO (3199|46912520506160) - ORB_Core::run, handle_events() returns 1
 161 TAO (3201|46912517403856) - Connection_Handler[5]::close_connection_eh, purging entry from cache
 162 TAO (3199|46912520506160) - ORB_Core::run, calling handle_events()
 163 TAO (3201|46912517403856) - Transport[5]::cleanup_queue_i, cleaning up complete queue
 164 TAO (3201|46912517403856) - Transport[5]::cleanup_queue_i, discarded 0 messages, 0 bytes.
 165 TAO (3199|46912520506160) - Connection_Handler[8]::handle_input, handle = 8/8
 166 TAO (3201|46912517403856) - Connection_Handler[5]::close_connection_eh, removing from the reactor
 167 TAO (3199|46912520506160) - Transport[8]::handle_input
 168 TAO (3201|46912517403856) - Connection_Handler[5]::close_connection_eh, cancel all timers
 169 TAO (3201|46912517403856) - Transport[5]::cleanup_queue_i, cleaning up complete queue
 170 TAO (3201|46912517403856) - Transport[5]::cleanup_queue_i, discarded 0 messages, 0 bytes.
 171 TAO (3201|46912517403856) - Connection_Handler[5]::close_connection_eh
 172 TAO (3201|46912517403856) - Transport[5]::cleanup_queue_i, cleaning up complete queue
 173 TAO (3201|46912517403856) - Transport[5]::cleanup_queue_i, discarded 0 messages, 0 bytes.
 174 TAO (3199|46912520506160) - Transport[8]::process_queue_head, 0 enqueued
 175 TAO (3199|46912520506160) - Transport[8]::handle_input_parse_data, enter
 176 TAO (3199|46912520506160) - Transport[8]::handle_input_parse_data, read 84 bytes
 177 TAO (3199|46912520506160) - GIOP_Message_State::parse_message_header_i
 178 TAO (3199|46912520506160) - GIOP_Message_State::get_version_info
 179 TAO (3199|46912520506160) - GIOP_Message_State::get_byte_order_info
 180 TAO (3199|46912520506160) - Transport[8]::process_parsed_messages, entering (missing data == 0)
 181 TAO (3199|46912520506160) - GIOP_Message_Base::dump_msg, recv GIOP v1.2 msg, 72 data bytes, my endian, not compressed, Type Request[2]
 182 GIOP message - HEXDUMP 84 bytes
 183 47 49 4f 50 01 02 01 00  48 00 00 00 02 00 00 00   GIOP....H.......
 184 00 00 00 00 00 00 00 00  23 00 00 00 14 01 0f 00   ........#.......
 185 4e 53 54 66 db 85 48 3c  ff 0a 00 01 00 00 00 01   NSTf..H<........
 186 00 00 00 00 00 00 00 01  00 00 00 01 00 00 00 00   ................
 187 09 00 00 00 73 68 75 74  64 6f 77 6e 00 67 00 00   ....shutdown.g..
 188 00 00 00 00                                        ....
 189 TAO (3201|46912517403856) - ORBInitializer_Registry::fini clearing 1 @0x8d50ff0
 190 TAO (3201|46912517403856) - ORBInitializer_Registry::fini clearing 0 @0x8d527f0
 191 TAO (3199|46912520506160) - Connection_Handler[8]::handle_input, handle = 8/8, retval = 0
 192 TAO (3199|46912520506160) - ORB_Core::run, handle_events() returns 1
 193 TAO (3199|46912520506160) - ORB_Core::run, ends with result = 1
 194 (3199|46912520506160) server - event loop finished
 195 CORBA::ORB::destroy() called on ORB <>.
 196 Destroying ORB <>
 197 TAO (3199|46912520506160) - Connection_Handler[8]::close_connection_eh, purging entry from cache
 198 TAO (3199|46912520506160) - Transport[8]::cleanup_queue_i, cleaning up complete queue
 199 TAO (3199|46912520506160) - Transport[8]::cleanup_queue_i, discarded 0 messages, 0 bytes.
 200 TAO (3199|46912520506160) - Connection_Handler[8]::close_connection_eh, removing from the reactor
 201 TAO (3199|46912520506160) - Connection_Handler[8]::close_connection_eh, cancel all timers
 202 TAO (3199|46912520506160) - Transport[8]::cleanup_queue_i, cleaning up complete queue
 203 TAO (3199|46912520506160) - Transport[8]::cleanup_queue_i, discarded 0 messages, 0 bytes.
 204 TAO (3199|46912520506160) - Connection_Handler[8]::close_connection_eh
 205 TAO (3199|46912520506160) - Transport[8]::cleanup_queue_i, cleaning up complete queue
 206 TAO (3199|46912520506160) - Transport[8]::cleanup_queue_i, discarded 0 messages, 0 bytes.
 207 TAO (3199|46912520506160) - ORBInitializer_Registry::fini clearing 1 @0x17b2e660
 208 TAO (3199|46912520506160) - ORBInitializer_Registry::fini clearing 0 @0x17b2fe60
 209