Regularly fails to transmit on PC with win7

278 views
Skip to first unread message

rper...@yahoo.com

unread,
Feb 21, 2012, 2:15:08 AM2/21/12
to ostinato
I recently tried out this s/w for sending multicast packets for
testing some software I'm developing. However, I encounter the
following problems regularly.

When I turn on "transmit" the Transmit State goes to "on" but the
"frames sent" field does not increment. If I monitor the network
(using Wireshark and the network activity LEDs) I see no activity.

If I then click the "off" button the transmit state remains in the
"on" state. So for example if I try to edit and apply the stream I get
a message saying I need to turn the transmit off so it seems to get
stuck in the on state.

If I reboot the s/w sometimes it does work for a while and then hangs
up. While it works it does work as expected and the data is correct.
However, getting it to work on average is successful about 20% of the
time and even then it does not make it for too long before it dies.

Any ideas?

Srivats P

unread,
Feb 21, 2012, 8:27:54 AM2/21/12
to rper...@yahoo.com, ostinato
1. What version/revision of Ostinato are you using (available in the
about dialog)?
2. Are you using "interleaved streams" transmission mode or
"sequential streams"? The default is "sequential streams".
3. Does this happen with any stream or with particular stream(s)?

Srivats

> --
> Get Ostinato News and Updates on Twitter - Follow @ostinato (http://twitter.com/ostinato)
> ---------
> You received this message because you are subscribed to the Google Groups "ostinato" group.
> To post to this group, send email to osti...@googlegroups.com
> To unsubscribe from this group, send email to ostinato+u...@googlegroups.com
> For more options, visit this group at http://groups.google.com/group/ostinato?hl=en

--
http://ostinato.org/
@ostinato

rper...@yahoo.com

unread,
Feb 21, 2012, 11:07:25 AM2/21/12
to ostinato
Thanks for the reply Srivats. To answer your questions -

1. Version is 0.5
2. The Port Config is setup as Sequential streams with the Exclusive
Control box checked. I guess I don't fully understand how this option
works.
3. So far I've only tried it with one stream although I have
experimented with the packet rate and total number of packets. That
does not appear to have any effect or change the behavior in any way.

Srivats P

unread,
Feb 21, 2012, 11:19:27 AM2/21/12
to rper...@yahoo.com, ostinato
Can you install DebugView
(http://technet.microsoft.com/en-us/sysinternals/bb896647.aspx) and
run debugview before you start Ostinato - logs will get captured in
DebugView. Once you see the problem, save the log from DebugView and
attach it here.

Srivats

rper...@yahoo.com

unread,
Feb 21, 2012, 11:38:28 AM2/21/12
to ostinato
Sure - will do that later today and post the results.

rper...@yahoo.com

unread,
Feb 21, 2012, 12:01:07 PM2/21/12
to ostinato
Well okay got it done sooner than I thought. Cannot see an easy way to
attach a file so I'm copying the contents directly to this post. Today
it seems to start working right away each time I tried it but it then
hangs after a while. It also stops working if I stop TX and then try
to restart. I've captured this scenario below. At the end of the log
file I'm clicking "start" and "stop" repeatedly with nothing going out
and the TX state stuck in transmit.

00000001 0.00000000 [6776] applying 'deleted streams' ...
00000002 0.00021207 [6776] client(CallMethod) sending 12 bytes
encoding <port_id {
00000003 0.00021207 [6776] id: 1
00000004 0.00021207 [6776] }
00000005 0.00021207 [6776] >
00000006 0.00033071 [6776] 0001000600000004
00000007 0.00048020 [6776] applying 'new streams' ...
00000008 0.00061526 [6776] RpcChannel: queueing method 5 since 6 is
pending; queued message = <port_id {
00000009 0.00061526 [6776] id: 1
00000010 0.00061526 [6776] }
00000011 0.00061526 [6776] >
00000012 0.00073220 [6776] pendingCallList size = 1
00000013 0.00084234 [6776] applying 'modified streams' ...
00000014 0.00095248 [6776] In getModifiedStreamsSinceLastSync
00000015 0.00109377 [6776] Done getModifiedStreamsSinceLastSync
00000016 0.00151480 [6776] RpcChannel: queueing method 7 since 6 is
pending; queued message = <port_id {
00000017 0.00151480 [6776] id: 1
00000018 0.00151480 [6776] }
00000019 0.00151480 [6776] stream {
00000020 0.00151480 [6776] stream_id {
00000021 0.00151480 [6776] id: 0
00000022 0.00151480 [6776] }
00000023 0.00151480 [6776] core {
00000024 0.00151480 [6776] name: "mcast_test"
00000025 0.00151480 [6776] is_enabled: true
00000026 0.00151480 [6776] ordinal: 0
00000027 0.00151480 [6776] len_mode: e_fl_fixed
00000028 0.00151480 [6776] frame_len: 64
00000029 0.00151480 [6776] frame_len_min: 64
00000030 0.00151480 [6776] frame_len_max: 1518
00000031 0.00151480 [6776] }
00000032 0.00151480 [6776] control {
00000033 0.00151480 [6776] unit: e_su_packets
00000034 0.00151480 [6776] mode: e_sm_fixed
00000035 0.00151480 [6776] num_packets: 20000
00000036 0.00151480 [6776] num_bursts: 1
00000037 0.00151480 [6776] packets_per_burst: 10
00000038 0.00151480 [6776] next: e_nw_stop
00000039 0.00151480 [6776] packets_per_sec: 5
00000040 0.00151480 [6776] bursts_per_sec: 1
00000041 0.00151480 [6776] }
00000042 0.00151480 [6776] protocol {
00000043 0.00151480 [6776] protocol_id {
00000044 0.00151480 [6776] id: 100
00000045 0.00151480 [6776] }
00000046 0.00151480 [6776] [OstProto.mac] {
00000047 0.00151480 [6776] dst_mac: 1101089015045
00000048 0.00151480 [6776] dst_mac_mode: e_mm_fixed
00000049 0.00151480 [6776] dst_mac_count: 16
00000050 0.00151480 [6776] dst_mac_step: 1
00000051 0.00151480 [6776] src_mac: 0
00000052 0.00151480 [6776] src_mac_mode: e_mm_fixed
00000053 0.00151480 [6776] src_mac_count: 16
00000054 0.00151480 [6776] src_mac_step: 1
00000055 0.00151480 [6776] }
00000056 0.00151480 [6776] }
00000057 0.00151480 [6776] protocol {
00000058 0.00151480 [6776] protocol_id {
00000059 0.00151480 [6776] id: 200
00000060 0.00151480 [6776] }
00000061 0.00151480 [6776] [OstProto.eth2] {
00000062 0.00151480 [6776] type: 2048
00000063 0.00151480 [6776] is_override_type: false
00000064 0.00151480 [6776] }
00000065 0.00151480 [6776] }
00000066 0.00151480 [6776] protocol {
00000067 0.00151480 [6776] protocol_id {
00000068 0.00151480 [6776] id: 301
00000069 0.00151480 [6776] }
00000070 0.00151480 [6776] [OstProto.ip4] {
00000071 0.00151480 [6776] is_override_ver: false
00000072 0.00151480 [6776] is_override_hdrlen: false
00000073 0.00151480 [6776] is_override_totlen: false
00000074 0.00151480 [6776] is_override_cksum: false
00000075 0.00151480 [6776] ver_hdrlen: 69
00000076 0.00151480 [6776] tos: 0
00000077 0.00151480 [6776] totlen: 46
00000078 0.00151480 [6776] id: 1234
00000079 0.00151480 [6776] flags: 0
00000080 0.00151480 [6776] frag_ofs: 0
00000081 0.00151480 [6776] ttl: 127
00000082 0.00151480 [6776] proto: 17
00000083 0.00151480 [6776] cksum: 18403
00000084 0.00151480 [6776] src_ip: 0
00000085 0.00151480 [6776] src_ip_mode: e_im_fixed
00000086 0.00151480 [6776] src_ip_count: 16
00000087 0.00151480 [6776] src_ip_mask: 4294967040
00000088 0.00151480 [6776] dst_ip: 3926197509
00000089 0.00151480 [6776] dst_ip_mode: e_im_fixed
00000090 0.00151480 [6776] dst_ip_count: 16
00000091 0.00151480 [6776] dst_ip_mask: 4294967040
00000092 0.00151480 [6776] is_override_proto: false
00000093 0.00151480 [6776] }
00000094 0.00151480 [6776] }
00000095 0.00151480 [6776] protocol {
00000096 0.00151480 [6776] protocol_id {
00000097 0.00151480 [6776] id: 401
00000098 0.00151480 [6776] }
00000099 0.00151480 [6776] [OstProto.udp] {
00000100 0.00151480 [6776] is_override_src_port: false
00000101 0.00151480 [6776] is_override_dst_port: true
00000102 0.00151480 [6776] is_override_totlen: false
00000103 0.00151480 [6776] is_override_cksum: false
00000104 0.00151480 [6776] src_port: 0
00000105 0.00151480 [6776] dst_port: 1078
00000106 0.00151480 [6776] totlen: 26
00000107 0.00151480 [6776] cksum: 50216
00000108 0.00151480 [6776] }
00000109 0.00151480 [6776] }
00000110 0.00151480 [6776] protocol {
00000111 0.00151480 [6776] protocol_id {
00000112 0.00151480 [6776] id: 101
00000113 0.00151480 [6776] }
00000114 0.00151480 [6776] [OstProto.payload] {
00000115 0.00151480 [6776] pattern_mode: e_dp_inc_byte
00000116 0.00151480 [6776] pattern: 0
00000117 0.00151480 [6776] }
00000118 0.00151480 [6776] }
00000119 0.00151480 [6776] }
00000120 0.00151480 [6776] >
00000121 0.00174754 [6776] pendingCallList size = 2
00000122 0.03651426 [6152] In virtual void
MyService::deleteStream(google::protobuf::RpcController*, const
OstProto::StreamIdList*, OstProto::Ack*, google::protobuf::Closure*)
00000123 0.03661137 [6152] Server(done): sending 8 bytes to client
encoding <>
00000124 0.03748486 [6776] client(on_mpSocket_readyRead): Parsed as
00000125 0.03753016 [6776] In processDeleteStreamAck
00000126 0.03758707 [6776] RpcChannel: executing queued method 5
<port_id {
00000127 0.03758707 [6776] id: 1
00000128 0.03758707 [6776] }
00000129 0.03758707 [6776] >
00000130 0.03763889 [6776] client(CallMethod) sending 12 bytes
encoding <port_id {
00000131 0.03763889 [6776] id: 1
00000132 0.03763889 [6776] }
00000133 0.03763889 [6776] >
00000134 0.03768278 [6776] 0001000500000004
00000135 0.03865536 [6152] In virtual void
MyService::addStream(google::protobuf::RpcController*, const
OstProto::StreamIdList*, OstProto::Ack*, google::protobuf::Closure*)
00000136 0.03869783 [6152] Server(done): sending 8 bytes to client
encoding <>
00000137 0.03885667 [6776] client(on_mpSocket_readyRead): Parsed as
00000138 0.03889999 [6776] In processAddStreamAck
00000139 0.03907384 [6776] RpcChannel: executing queued method 7
<port_id {
00000140 0.03907384 [6776] id: 1
00000141 0.03907384 [6776] }
00000142 0.03907384 [6776] stream {
00000143 0.03907384 [6776] stream_id {
00000144 0.03907384 [6776] id: 0
00000145 0.03907384 [6776] }
00000146 0.03907384 [6776] core {
00000147 0.03907384 [6776] name: "mcast_test"
00000148 0.03907384 [6776] is_enabled: true
00000149 0.03907384 [6776] ordinal: 0
00000150 0.03907384 [6776] len_mode: e_fl_fixed
00000151 0.03907384 [6776] frame_len: 64
00000152 0.03907384 [6776] frame_len_min: 64
00000153 0.03907384 [6776] frame_len_max: 1518
00000154 0.03907384 [6776] }
00000155 0.03907384 [6776] control {
00000156 0.03907384 [6776] unit: e_su_packets
00000157 0.03907384 [6776] mode: e_sm_fixed
00000158 0.03907384 [6776] num_packets: 20000
00000159 0.03907384 [6776] num_bursts: 1
00000160 0.03907384 [6776] packets_per_burst: 10
00000161 0.03907384 [6776] next: e_nw_stop
00000162 0.03907384 [6776] packets_per_sec: 5
00000163 0.03907384 [6776] bursts_per_sec: 1
00000164 0.03907384 [6776] }
00000165 0.03907384 [6776] protocol {
00000166 0.03907384 [6776] protocol_id {
00000167 0.03907384 [6776] id: 100
00000168 0.03907384 [6776] }
00000169 0.03907384 [6776] [OstProto.mac] {
00000170 0.03907384 [6776] dst_mac: 1101089015045
00000171 0.03907384 [6776] dst_mac_mode: e_mm_fixed
00000172 0.03907384 [6776] dst_mac_count: 16
00000173 0.03907384 [6776] dst_mac_step: 1
00000174 0.03907384 [6776] src_mac: 0
00000175 0.03907384 [6776] src_mac_mode: e_mm_fixed
00000176 0.03907384 [6776] src_mac_count: 16
00000177 0.03907384 [6776] src_mac_step: 1
00000178 0.03907384 [6776] }
00000179 0.03907384 [6776] }
00000180 0.03907384 [6776] protocol {
00000181 0.03907384 [6776] protocol_id {
00000182 0.03907384 [6776] id: 200
00000183 0.03907384 [6776] }
00000184 0.03907384 [6776] [OstProto.eth2] {
00000185 0.03907384 [6776] type: 2048
00000186 0.03907384 [6776] is_override_type: false
00000187 0.03907384 [6776] }
00000188 0.03907384 [6776] }
00000189 0.03907384 [6776] protocol {
00000190 0.03907384 [6776] protocol_id {
00000191 0.03907384 [6776] id: 301
00000192 0.03907384 [6776] }
00000193 0.03907384 [6776] [OstProto.ip4] {
00000194 0.03907384 [6776] is_override_ver: false
00000195 0.03907384 [6776] is_override_hdrlen: false
00000196 0.03907384 [6776] is_override_totlen: false
00000197 0.03907384 [6776] is_override_cksum: false
00000198 0.03907384 [6776] ver_hdrlen: 69
00000199 0.03907384 [6776] tos: 0
00000200 0.03907384 [6776] totlen: 46
00000201 0.03907384 [6776] id: 1234
00000202 0.03907384 [6776] flags: 0
00000203 0.03907384 [6776] frag_ofs: 0
00000204 0.03907384 [6776] ttl: 127
00000205 0.03907384 [6776] proto: 17
00000206 0.03907384 [6776] cksum: 18403
00000207 0.03907384 [6776] src_ip: 0
00000208 0.03907384 [6776] src_ip_mode: e_im_fixed
00000209 0.03907384 [6776] src_ip_count: 16
00000210 0.03907384 [6776] src_ip_mask: 4294967040
00000211 0.03907384 [6776] dst_ip: 3926197509
00000212 0.03907384 [6776] dst_ip_mode: e_im_fixed
00000213 0.03907384 [6776] dst_ip_count: 16
00000214 0.03907384 [6776] dst_ip_mask: 4294967040
00000215 0.03907384 [6776] is_override_proto: false
00000216 0.03907384 [6776] }
00000217 0.03907384 [6776] }
00000218 0.03907384 [6776] protocol {
00000219 0.03907384 [6776] protocol_id {
00000220 0.03907384 [6776] id: 401
00000221 0.03907384 [6776] }
00000222 0.03907384 [6776] [OstProto.udp] {
00000223 0.03907384 [6776] is_override_src_port: false
00000224 0.03907384 [6776] is_override_dst_port: true
00000225 0.03907384 [6776] is_override_totlen: false
00000226 0.03907384 [6776] is_override_cksum: false
00000227 0.03907384 [6776] src_port: 0
00000228 0.03907384 [6776] dst_port: 1078
00000229 0.03907384 [6776] totlen: 26
00000230 0.03907384 [6776] cksum: 50216
00000231 0.03907384 [6776] }
00000232 0.03907384 [6776] }
00000233 0.03907384 [6776] protocol {
00000234 0.03907384 [6776] protocol_id {
00000235 0.03907384 [6776] id: 101
00000236 0.03907384 [6776] }
00000237 0.03907384 [6776] [OstProto.payload] {
00000238 0.03907384 [6776] pattern_mode: e_dp_inc_byte
00000239 0.03907384 [6776] pattern: 0
00000240 0.03907384 [6776] }
00000241 0.03907384 [6776] }
00000242 0.03907384 [6776] }
00000243 0.03907384 [6776] >
00000244 0.03923863 [6776] client(CallMethod) sending 245 bytes
encoding <port_id {
00000245 0.03923863 [6776] id: 1
00000246 0.03923863 [6776] }
00000247 0.03923863 [6776] stream {
00000248 0.03923863 [6776] stream_id {
00000249 0.03923863 [6776] id: 0
00000250 0.03923863 [6776] }
00000251 0.03923863 [6776] core {
00000252 0.03923863 [6776] name: "mcast_test"
00000253 0.03923863 [6776] is_enabled: true
00000254 0.03923863 [6776] ordinal: 0
00000255 0.03923863 [6776] len_mode: e_fl_fixed
00000256 0.03923863 [6776] frame_len: 64
00000257 0.03923863 [6776] frame_len_min: 64
00000258 0.03923863 [6776] frame_len_max: 1518
00000259 0.03923863 [6776] }
00000260 0.03923863 [6776] control {
00000261 0.03923863 [6776] unit: e_su_packets
00000262 0.03923863 [6776] mode: e_sm_fixed
00000263 0.03923863 [6776] num_packets: 20000
00000264 0.03923863 [6776] num_bursts: 1
00000265 0.03923863 [6776] packets_per_burst: 10
00000266 0.03923863 [6776] next: e_nw_stop
00000267 0.03923863 [6776] packets_per_sec: 5
00000268 0.03923863 [6776] bursts_per_sec: 1
00000269 0.03923863 [6776] }
00000270 0.03923863 [6776] protocol {
00000271 0.03923863 [6776] protocol_id {
00000272 0.03923863 [6776] id: 100
00000273 0.03923863 [6776] }
00000274 0.03923863 [6776] [OstProto.mac] {
00000275 0.03923863 [6776] dst_mac: 1101089015045
00000276 0.03923863 [6776] dst_mac_mode: e_mm_fixed
00000277 0.03923863 [6776] dst_mac_count: 16
00000278 0.03923863 [6776] dst_mac_step: 1
00000279 0.03923863 [6776] src_mac: 0
00000280 0.03923863 [6776] src_mac_mode: e_mm_fixed
00000281 0.03923863 [6776] src_mac_count: 16
00000282 0.03923863 [6776] src_mac_step: 1
00000283 0.03923863 [6776] }
00000284 0.03923863 [6776] }
00000285 0.03923863 [6776] protocol {
00000286 0.03923863 [6776] protocol_id {
00000287 0.03923863 [6776] id: 200
00000288 0.03923863 [6776] }
00000289 0.03923863 [6776] [OstProto.eth2] {
00000290 0.03923863 [6776] type: 2048
00000291 0.03923863 [6776] is_override_type: false
00000292 0.03923863 [6776] }
00000293 0.03923863 [6776] }
00000294 0.03923863 [6776] protocol {
00000295 0.03923863 [6776] protocol_id {
00000296 0.03923863 [6776] id: 301
00000297 0.03923863 [6776] }
00000298 0.03923863 [6776] [OstProto.ip4] {
00000299 0.03923863 [6776] is_override_ver: false
00000300 0.03923863 [6776] is_override_hdrlen: false
00000301 0.03923863 [6776] is_override_totlen: false
00000302 0.03923863 [6776] is_override_cksum: false
00000303 0.03923863 [6776] ver_hdrlen: 69
00000304 0.03923863 [6776] tos: 0
00000305 0.03923863 [6776] totlen: 46
00000306 0.03923863 [6776] id: 1234
00000307 0.03923863 [6776] flags: 0
00000308 0.03923863 [6776] frag_ofs: 0
00000309 0.03923863 [6776] ttl: 127
00000310 0.03923863 [6776] proto: 17
00000311 0.03923863 [6776] cksum: 18403
00000312 0.03923863 [6776] src_ip: 0
00000313 0.03923863 [6776] src_ip_mode: e_im_fixed
00000314 0.03923863 [6776] src_ip_count: 16
00000315 0.03923863 [6776] src_ip_mask: 4294967040
00000316 0.03923863 [6776] dst_ip: 3926197509
00000317 0.03923863 [6776] dst_ip_mode: e_im_fixed
00000318 0.03923863 [6776] dst_ip_count: 16
00000319 0.03923863 [6776] dst_ip_mask: 4294967040
00000320 0.03923863 [6776] is_override_proto: false
00000321 0.03923863 [6776] }
00000322 0.03923863 [6776] }
00000323 0.03923863 [6776] protocol {
00000324 0.03923863 [6776] protocol_id {
00000325 0.03923863 [6776] id: 401
00000326 0.03923863 [6776] }
00000327 0.03923863 [6776] [OstProto.udp] {
00000328 0.03923863 [6776] is_override_src_port: false
00000329 0.03923863 [6776] is_override_dst_port: true
00000330 0.03923863 [6776] is_override_totlen: false
00000331 0.03923863 [6776] is_override_cksum: false
00000332 0.03923863 [6776] src_port: 0
00000333 0.03923863 [6776] dst_port: 1078
00000334 0.03923863 [6776] totlen: 26
00000335 0.03923863 [6776] cksum: 50216
00000336 0.03923863 [6776] }
00000337 0.03923863 [6776] }
00000338 0.03923863 [6776] protocol {
00000339 0.03923863 [6776] protocol_id {
00000340 0.03923863 [6776] id: 101
00000341 0.03923863 [6776] }
00000342 0.03923863 [6776] [OstProto.payload] {
00000343 0.03923863 [6776] pattern_mode: e_dp_inc_byte
00000344 0.03923863 [6776] pattern: 0
00000345 0.03923863 [6776] }
00000346 0.03923863 [6776] }
00000347 0.03923863 [6776] }
00000348 0.03923863 [6776] >
00000349 0.03932725 [6776] 00010007000000ed
00000350 0.03952488 [6152] In virtual void
MyService::modifyStream(google::protobuf::RpcController*, const
OstProto::StreamConfigList*, OstProto::Ack*,
google::protobuf::Closure*)
00000351 0.03958236 [6152] In updatePacketListSequential
00000352 0.03965003 [6152]
00000353 0.03965003 [6152] frameVariableCount = 1
00000354 0.03969023 [6152] n = 78, x = 256, y = 32, burstSize = 288
00000355 0.03973016 [6152] ibg = 0
00000356 0.03976923 [6152] ibg1 = 0
00000357 0.03980632 [6152] nb1 = 0
00000358 0.03984370 [6152] ibg2 = 0
00000359 0.03988079 [6152] nb2 = 0
00000360 0.03988079 [6152]
00000361 0.03991986 [6152] ipg = 2e+008
00000362 0.03995780 [6152] ipg1 = 200000000
00000363 0.03999489 [6152] npx1 = 0
00000364 0.04003198 [6152] npy1 = 0
00000365 0.04006936 [6152] ipg2 = 200000000
00000366 0.04010786 [6152] npx2 = 256
00000367 0.04014552 [6152] npy2 = 32
00000368 0.04014552 [6152]
00000369 0.04019791 [6152] <<< (0, 0b) >>>
00000370 0.04023726 [6152] < 0: (48b/6B) 01005e050505 >
00000371 0.04027832 [6152] <<< (6, 0b) 01005e050505 >>>
00000372 0.04031710 [6152] < 1: (48b/6B) 000000000000 >
00000373 0.04035759 [6152] payloadProtocolId: payloadProtocolId =
0x800
00000374 0.04039695 [6152] payloadProtocolId: payloadProtocolId =
0x800
00000375 0.04043602 [6152] <<< (0, 0b) >>>
00000376 0.04047595 [6152] < 0: (16b/2B) 0800 >
00000377 0.04051644 [6152] <<< (0, 0b) >>>
00000378 0.04055551 [6152] < 0: (4b/1B) 04 >
00000379 0.04059543 [6152] <<< (1, 4b) 40 >>>
00000380 0.04063337 [6152] < 1: (4b/1B) 05 >
00000381 0.04067358 [6152] <<< (1, 0b) 45 >>>
00000382 0.04071152 [6152] < 2: (8b/1B) 00 >
00000383 0.04075314 [6152] payloadProtocolId: payloadProtocolId = 0x0
00000384 0.04079391 [6152] protocolFrameSize: protoSize = 8
00000385 0.04083327 [6152] protocolFrameSize: protoSize = 8
00000386 0.04087546 [6152] payloadProtocolId: payloadProtocolId =
0x11
00000387 0.04091708 [6152] protocolFrameSize: protoSize = 20
00000388 0.04095530 [6152] payloadProtocolId: payloadProtocolId =
0x800
00000389 0.04099636 [6152] protocolFrameSize: protoSize = 2
00000390 0.04104591 [6152] protocolFrameSize: protoSize = 12
00000391 0.04108441 [6152] protocolFrameOffset: ofs = 42
00000392 0.04112377 [6152] protocolFrameSize: this = 04870240,
streamIndex = 0, len = 18
00000393 0.04116143 [6152] protocolFramePayloadSize: payloadSize = 26
00000394 0.04120192 [6152] <<< (2, 0b) 4500 >>>
00000395 0.04124099 [6152] < 3: (16b/2B) 002e >
00000396 0.04128148 [6152] <<< (4, 0b) 4500002e >>>
00000397 0.04132027 [6152] < 4: (16b/2B) 04d2 >
00000398 0.04136019 [6152] <<< (6, 0b) 4500002e04d2 >>>
00000399 0.04139870 [6152] < 5: (3b/1B) 00 >
00000400 0.04143890 [6152] <<< (7, 3b) 4500002e04d200 >>>
00000401 0.04147741 [6152] < 6: (13b/2B) 0000 >
00000402 0.04151819 [6152] <<< (8, 0b) 4500002e04d20000 >>>
00000403 0.04155782 [6152] < 7: (8b/1B) 7f >
00000404 0.04159718 [6152] payloadProtocolId: payloadProtocolId =
0x11
00000405 0.04163625 [6152] payloadProtocolId: payloadProtocolId =
0x11
00000406 0.04167589 [6152] <<< (9, 0b) 4500002e04d200007f >>>
00000407 0.04171383 [6152] < 8: (8b/1B) 11 >
00000408 0.04175800 [6152] <<< (0, 0b) >>>
00000409 0.04180472 [6152] < 0: (4b/1B) 04 >
00000410 0.04184408 [6152] <<< (1, 4b) 40 >>>
00000411 0.04188230 [6152] < 1: (4b/1B) 05 >
00000412 0.04192336 [6152] <<< (1, 0b) 45 >>>
00000413 0.04196158 [6152] < 2: (8b/1B) 00 >
00000414 0.04200066 [6152] protocolFrameSize: protoSize = 8
00000415 0.04203831 [6152] protocolFrameSize: protoSize = 8
00000416 0.04207597 [6152] protocolFrameSize: protoSize = 20
00000417 0.04211334 [6152] protocolFrameSize: protoSize = 2
00000418 0.04215100 [6152] protocolFrameSize: protoSize = 12
00000419 0.04218866 [6152] protocolFrameOffset: ofs = 42
00000420 0.04222716 [6152] protocolFrameSize: this = 04870240,
streamIndex = 0, len = 18
00000421 0.04226596 [6152] protocolFramePayloadSize: payloadSize = 26
00000422 0.04230475 [6152] <<< (2, 0b) 4500 >>>
00000423 0.04234325 [6152] < 3: (16b/2B) 002e >
00000424 0.04238346 [6152] <<< (4, 0b) 4500002e >>>
00000425 0.04242168 [6152] < 4: (16b/2B) 04d2 >
00000426 0.04246160 [6152] <<< (6, 0b) 4500002e04d2 >>>
00000427 0.04249983 [6152] < 5: (3b/1B) 00 >
00000428 0.04253947 [6152] <<< (7, 3b) 4500002e04d200 >>>
00000429 0.04257769 [6152] < 6: (13b/2B) 0000 >
00000430 0.04261903 [6152] <<< (8, 0b) 4500002e04d20000 >>>
00000431 0.04265697 [6152] < 7: (8b/1B) 7f >
00000432 0.04269548 [6152] payloadProtocolId: payloadProtocolId =
0x11
00000433 0.04273427 [6152] payloadProtocolId: payloadProtocolId =
0x11
00000434 0.04277391 [6152] <<< (9, 0b) 4500002e04d200007f >>>
00000435 0.04281185 [6152] < 8: (8b/1B) 11 >
00000436 0.04285149 [6152] <<< (10, 0b) 4500002e04d200007f11 >>>
00000437 0.04288999 [6152] < 9: (16b/2B) 0000 >
00000438 0.04293048 [6152] <<< (12, 0b) 4500002e04d200007f110000 >>>
00000439 0.04297012 [6152] < 10: (32b/4B) 00000000 >
00000440 0.04301118 [6152] <<< (16, 0b)
4500002e04d200007f11000000000000 >>>
00000441 0.04304997 [6152] < 11: (32b/4B) ea050505 >
00000442 0.04308989 [6152] <<< (10, 0b) 4500002e04d200007f11 >>>
00000443 0.04312811 [6152] < 9: (16b/2B) 47e3 >
00000444 0.04316860 [6152] <<< (12, 0b) 4500002e04d200007f1147e3 >>>
00000445 0.04320683 [6152] < 10: (32b/4B) 00000000 >
00000446 0.04324788 [6152] <<< (16, 0b)
4500002e04d200007f1147e300000000 >>>
00000447 0.04328639 [6152] < 11: (32b/4B) ea050505 >
00000448 0.04332688 [6152] payloadProtocolId: payloadProtocolId = 0x0
00000449 0.04336567 [6152] payloadProtocolId: payloadProtocolId = 0x0
00000450 0.04340446 [6152] <<< (0, 0b) >>>
00000451 0.04344297 [6152] < 0: (16b/2B) 0000 >
00000452 0.04348289 [6152] <<< (2, 0b) 0000 >>>
00000453 0.04352111 [6152] < 1: (16b/2B) 0436 >
00000454 0.04355962 [6152] protocolFrameSize: protoSize = 8
00000455 0.04359728 [6152] protocolFrameSize: protoSize = 20
00000456 0.04363437 [6152] protocolFrameSize: protoSize = 2
00000457 0.04367344 [6152] protocolFrameSize: protoSize = 12
00000458 0.04371082 [6152] protocolFrameOffset: ofs = 42
00000459 0.04374904 [6152] protocolFrameSize: this = 04870240,
streamIndex = 0, len = 18
00000460 0.04378641 [6152] protocolFramePayloadSize: payloadSize = 18
00000461 0.04382521 [6152] <<< (4, 0b) 00000436 >>>
00000462 0.04386343 [6152] < 2: (16b/2B) 001a >
00000463 0.04390250 [6152] payloadProtocolId: payloadProtocolId = 0x0
00000464 0.04394158 [6152] payloadProtocolId: payloadProtocolId = 0x0
00000465 0.04398037 [6152] <<< (0, 0b) >>>
00000466 0.04401972 [6152] < 0: (16b/2B) 0000 >
00000467 0.04405993 [6152] <<< (2, 0b) 0000 >>>
00000468 0.04409815 [6152] < 1: (16b/2B) 0436 >
00000469 0.04413694 [6152] protocolFrameSize: protoSize = 8
00000470 0.04417460 [6152] protocolFrameSize: protoSize = 20
00000471 0.04421169 [6152] protocolFrameSize: protoSize = 2
00000472 0.04424935 [6152] protocolFrameSize: protoSize = 12
00000473 0.04428672 [6152] protocolFrameOffset: ofs = 42
00000474 0.04432495 [6152] protocolFrameSize: this = 04870240,
streamIndex = 0, len = 18
00000475 0.04436374 [6152] protocolFramePayloadSize: payloadSize = 18
00000476 0.04440281 [6152] <<< (4, 0b) 00000436 >>>
00000477 0.04444103 [6152] < 2: (16b/2B) 001a >
00000478 0.04448039 [6152] <<< (6, 0b) 00000436001a >>>
00000479 0.04451862 [6152] < 3: (16b/2B) 0000 >
00000480 0.04455825 [6152] protocolFrameSize: protoSize = 8
00000481 0.04459563 [6152] protocolFrameSize: protoSize = 20
00000482 0.04463300 [6152] protocolFrameSize: protoSize = 2
00000483 0.04467038 [6152] protocolFrameSize: protoSize = 12
00000484 0.04470917 [6152] protocolFrameOffset: ofs = 42
00000485 0.04474711 [6152] protocolFrameSize: this = 04870240,
streamIndex = 0, len = 18
00000486 0.04478675 [6152] protocolFrameSize: protoSize = 8
00000487 0.04482441 [6152] protocolFrameSize: protoSize = 20
00000488 0.04486206 [6152] protocolFrameSize: protoSize = 2
00000489 0.04489944 [6152] protocolFrameSize: protoSize = 12
00000490 0.04493653 [6152] protocolFrameOffset: ofs = 42
00000491 0.04497475 [6152] protocolFrameSize: this = 04870240,
streamIndex = 0, len = 18
00000492 0.04501354 [6152] <<< (0, 0b) >>>
00000493 0.04505432 [6152] < 0: (144b/18B)
000102030405060708090a0b0c0d0e0f1011 >
00000494 0.04509480 [6152] payloadProtocolId: payloadProtocolId =
0x11
00000495 0.04513275 [6152] protocolFrameSize: protoSize = 8
00000496 0.04516983 [6152] protocolFrameSize: protoSize = 8
00000497 0.04520778 [6152] protocolFrameSize: protoSize = 20
00000498 0.04524487 [6152] protocolFrameSize: protoSize = 2
00000499 0.04528224 [6152] protocolFrameSize: protoSize = 12
00000500 0.04531962 [6152] protocolFrameOffset: ofs = 42
00000501 0.04535784 [6152] protocolFrameSize: this = 04870240,
streamIndex = 0, len = 18
00000502 0.04539691 [6152] protocolFramePayloadSize: payloadSize = 26
00000503 0.04543514 [6152] protocolFrameHeaderCksum: sum = 61237,
cksum = 4298
00000504 0.04547279 [6152] UDP cksum = 50216
00000505 0.04551187 [6152] <<< (6, 0b) 00000436001a >>>
00000506 0.04555038 [6152] < 3: (16b/2B) c428 >
00000507 0.04558916 [6152] protocolFrameSize: protoSize = 8
00000508 0.04562654 [6152] protocolFrameSize: protoSize = 20
00000509 0.04566420 [6152] protocolFrameSize: protoSize = 2
00000510 0.04570157 [6152] protocolFrameSize: protoSize = 12
00000511 0.04574008 [6152] protocolFrameOffset: ofs = 42
00000512 0.04577802 [6152] protocolFrameSize: this = 04870240,
streamIndex = 0, len = 18
00000513 0.04581766 [6152] protocolFrameSize: protoSize = 8
00000514 0.04585560 [6152] protocolFrameSize: protoSize = 20
00000515 0.04589297 [6152] protocolFrameSize: protoSize = 2
00000516 0.04593035 [6152] protocolFrameSize: protoSize = 12
00000517 0.04596744 [6152] protocolFrameOffset: ofs = 42
00000518 0.04600538 [6152] protocolFrameSize: this = 04870240,
streamIndex = 0, len = 18
00000519 0.04604389 [6152] <<< (0, 0b) >>>
00000520 0.04608466 [6152] < 0: (144b/18B)
000102030405060708090a0b0c0d0e0f1011 >
00000521 0.04612373 [6152] q(0, 0) sec = 0 nsec = 0
00000522 0.04616224 [6152] q(0, 1) sec = 0 nsec = 200000000
00000523 0.04620047 [6152] q(0, 2) sec = 0 nsec = 400000000
00000524 0.04623869 [6152] q(0, 3) sec = 0 nsec = 600000000
00000525 0.04627663 [6152] q(0, 4) sec = 0 nsec = 800000000
00000526 0.04631429 [6152] q(0, 5) sec = 1 nsec = 0
00000527 0.04635223 [6152] q(0, 6) sec = 1 nsec = 200000000
00000528 0.04639017 [6152] q(0, 7) sec = 1 nsec = 400000000
00000529 0.04642924 [6152] q(0, 8) sec = 1 nsec = 600000000
00000530 0.04646746 [6152] q(0, 9) sec = 1 nsec = 800000000
00000531 0.04650541 [6152] q(0, 10) sec = 2 nsec = 0
00000532 0.04654334 [6152] q(0, 11) sec = 2 nsec = 200000000
00000533 0.04658129 [6152] q(0, 12) sec = 2 nsec = 400000000
00000534 0.04661895 [6152] q(0, 13) sec = 2 nsec = 600000000
00000535 0.04665660 [6152] q(0, 14) sec = 2 nsec = 800000000
00000536 0.04669426 [6152] q(0, 15) sec = 3 nsec = 0
00000537 0.04673220 [6152] q(0, 16) sec = 3 nsec = 200000000
00000538 0.04677127 [6152] q(0, 17) sec = 3 nsec = 400000000
00000539 0.04680922 [6152] q(0, 18) sec = 3 nsec = 600000000
00000540 0.04684715 [6152] q(0, 19) sec = 3 nsec = 800000000
00000541 0.04688510 [6152] q(0, 20) sec = 4 nsec = 0
00000542 0.04692304 [6152] q(0, 21) sec = 4 nsec = 200000000
00000543 0.04696069 [6152] q(0, 22) sec = 4 nsec = 400000000
00000544 0.04699835 [6152] q(0, 23) sec = 4 nsec = 600000000
00000545 0.04703629 [6152] q(0, 24) sec = 4 nsec = 800000000
00000546 0.04707367 [6152] q(0, 25) sec = 5 nsec = 0
00000547 0.04711274 [6152] q(0, 26) sec = 5 nsec = 200000000
00000548 0.04715068 [6152] q(0, 27) sec = 5 nsec = 400000000
00000549 0.04718862 [6152] q(0, 28) sec = 5 nsec = 600000000
00000550 0.04722656 [6152] q(0, 29) sec = 5 nsec = 800000000
00000551 0.04726422 [6152] q(0, 30) sec = 6 nsec = 0
00000552 0.04730188 [6152] q(0, 31) sec = 6 nsec = 200000000
00000553 0.04733954 [6152] q(0, 32) sec = 6 nsec = 400000000
00000554 0.04737747 [6152] q(0, 33) sec = 6 nsec = 600000000
00000555 0.04741513 [6152] q(0, 34) sec = 6 nsec = 800000000
00000556 0.04745477 [6152] q(0, 35) sec = 7 nsec = 0
00000557 0.04749243 [6152] q(0, 36) sec = 7 nsec = 200000000
00000558 0.04753037 [6152] q(0, 37) sec = 7 nsec = 400000000
00000559 0.04756831 [6152] q(0, 38) sec = 7 nsec = 600000000
00000560 0.04760569 [6152] q(0, 39) sec = 7 nsec = 800000000
00000561 0.04764334 [6152] q(0, 40) sec = 8 nsec = 0
00000562 0.04768128 [6152] q(0, 41) sec = 8 nsec = 200000000
00000563 0.04771894 [6152] q(0, 42) sec = 8 nsec = 400000000
00000564 0.04775660 [6152] q(0, 43) sec = 8 nsec = 600000000
00000565 0.04779567 [6152] q(0, 44) sec = 8 nsec = 800000000
00000566 0.04783361 [6152] q(0, 45) sec = 9 nsec = 0
00000567 0.04787155 [6152] q(0, 46) sec = 9 nsec = 200000000
00000568 0.04790949 [6152] q(0, 47) sec = 9 nsec = 400000000
00000569 0.04794744 [6152] q(0, 48) sec = 9 nsec = 600000000
00000570 0.04798509 [6152] q(0, 49) sec = 9 nsec = 800000000
00000571 0.04802247 [6152] q(0, 50) sec = 10 nsec = 0
00000572 0.04806069 [6152] q(0, 51) sec = 10 nsec = 200000000
00000573 0.04809835 [6152] q(0, 52) sec = 10 nsec = 400000000
00000574 0.04813742 [6152] q(0, 53) sec = 10 nsec = 600000000
00000575 0.04817735 [6152] q(0, 54) sec = 10 nsec = 800000000
00000576 0.04821557 [6152] q(0, 55) sec = 11 nsec = 0
00000577 0.04825351 [6152] q(0, 56) sec = 11 nsec = 200000000
00000578 0.04829145 [6152] q(0, 57) sec = 11 nsec = 400000000
00000579 0.04832911 [6152] q(0, 58) sec = 11 nsec = 600000000
00000580 0.04836677 [6152] q(0, 59) sec = 11 nsec = 800000000
00000581 0.04840470 [6152] q(0, 60) sec = 12 nsec = 0
00000582 0.04844265 [6152] q(0, 61) sec = 12 nsec = 200000000
00000583 0.04848172 [6152] q(0, 62) sec = 12 nsec = 400000000
00000584 0.04851994 [6152] q(0, 63) sec = 12 nsec = 600000000
00000585 0.04855788 [6152] q(0, 64) sec = 12 nsec = 800000000
00000586 0.04859526 [6152] q(0, 65) sec = 13 nsec = 0
00000587 0.04863292 [6152] q(0, 66) sec = 13 nsec = 200000000
00000588 0.04867086 [6152] q(0, 67) sec = 13 nsec = 400000000
00000589 0.04870851 [6152] q(0, 68) sec = 13 nsec = 600000000
00000590 0.04874646 [6152] q(0, 69) sec = 13 nsec = 800000000
00000591 0.04878411 [6152] q(0, 70) sec = 14 nsec = 0
00000592 0.04882319 [6152] q(0, 71) sec = 14 nsec = 200000000
00000593 0.04886113 [6152] q(0, 72) sec = 14 nsec = 400000000
00000594 0.04889907 [6152] q(0, 73) sec = 14 nsec = 600000000
00000595 0.04893701 [6152] q(0, 74) sec = 14 nsec = 800000000
00000596 0.04897467 [6152] q(0, 75) sec = 15 nsec = 0
00000597 0.04901233 [6152] q(0, 76) sec = 15 nsec = 200000000
00000598 0.04905026 [6152] q(0, 77) sec = 15 nsec = 400000000
00000599 0.04908821 [6152] q(0, 78) sec = 15 nsec = 600000000
00000600 0.04912643 [6152] q(0, 79) sec = 15 nsec = 800000000
00000601 0.04916522 [6152] q(0, 80) sec = 16 nsec = 0
00000602 0.04920316 [6152] q(0, 81) sec = 16 nsec = 200000000
00000603 0.04924110 [6152] q(0, 82) sec = 16 nsec = 400000000
00000604 0.04927904 [6152] q(0, 83) sec = 16 nsec = 600000000
00000605 0.04931670 [6152] q(0, 84) sec = 16 nsec = 800000000
00000606 0.04935464 [6152] q(0, 85) sec = 17 nsec = 0
00000607 0.04939230 [6152] q(0, 86) sec = 17 nsec = 200000000
00000608 0.04942995 [6152] q(0, 87) sec = 17 nsec = 400000000
00000609 0.04946790 [6152] q(0, 88) sec = 17 nsec = 600000000
00000610 0.04950725 [6152] q(0, 89) sec = 17 nsec = 800000000
00000611 0.04954491 [6152] q(0, 90) sec = 18 nsec = 0
00000612 0.04958285 [6152] q(0, 91) sec = 18 nsec = 200000000
00000613 0.04962051 [6152] q(0, 92) sec = 18 nsec = 400000000
00000614 0.04965873 [6152] q(0, 93) sec = 18 nsec = 600000000
00000615 0.04969667 [6152] q(0, 94) sec = 18 nsec = 800000000
00000616 0.04973433 [6152] q(0, 95) sec = 19 nsec = 0
00000617 0.04977227 [6152] q(0, 96) sec = 19 nsec = 200000000
00000618 0.04980993 [6152] q(0, 97) sec = 19 nsec = 400000000
00000619 0.04984928 [6152] q(0, 98) sec = 19 nsec = 600000000
00000620 0.04988694 [6152] q(0, 99) sec = 19 nsec = 800000000
00000621 0.04992460 [6152] q(0, 100) sec = 20 nsec = 0
00000622 0.04996282 [6152] q(0, 101) sec = 20 nsec = 200000000
00000623 0.05000048 [6152] q(0, 102) sec = 20 nsec = 400000000
00000624 0.05003842 [6152] q(0, 103) sec = 20 nsec = 600000000
00000625 0.05007636 [6152] q(0, 104) sec = 20 nsec = 800000000
00000626 0.05011402 [6152] q(0, 105) sec = 21 nsec = 0
00000627 0.05015196 [6152] q(0, 106) sec = 21 nsec = 200000000
00000628 0.05019103 [6152] q(0, 107) sec = 21 nsec = 400000000
00000629 0.05023152 [6152] q(0, 108) sec = 21 nsec = 600000000
00000630 0.05026975 [6152] q(0, 109) sec = 21 nsec = 800000000
00000631 0.05030797 [6152] q(0, 110) sec = 22 nsec = 0
00000632 0.05034591 [6152] q(0, 111) sec = 22 nsec = 200000000
00000633 0.05038385 [6152] q(0, 112) sec = 22 nsec = 400000000
00000634 0.05042151 [6152] q(0, 113) sec = 22 nsec = 600000000
00000635 0.05045945 [6152] q(0, 114) sec = 22 nsec = 800000000
00000636 0.05049711 [6152] q(0, 115) sec = 23 nsec = 0
00000637 0.05053703 [6152] q(0, 116) sec = 23 nsec = 200000000
00000638 0.05057469 [6152] q(0, 117) sec = 23 nsec = 400000000
00000639 0.05061291 [6152] q(0, 118) sec = 23 nsec = 600000000
00000640 0.05065057 [6152] q(0, 119) sec = 23 nsec = 800000000
00000641 0.05068823 [6152] q(0, 120) sec = 24 nsec = 0
00000642 0.05072617 [6152] q(0, 121) sec = 24 nsec = 200000000
00000643 0.05076411 [6152] q(0, 122) sec = 24 nsec = 400000000
00000644 0.05080233 [6152] q(0, 123) sec = 24 nsec = 600000000
00000645 0.05083999 [6152] q(0, 124) sec = 24 nsec = 800000000
00000646 0.05087906 [6152] q(0, 125) sec = 25 nsec = 0
00000647 0.05091700 [6152] q(0, 126) sec = 25 nsec = 200000000
00000648 0.05095523 [6152] q(0, 127) sec = 25 nsec = 400000000
00000649 0.05099317 [6152] q(0, 128) sec = 25 nsec = 600000000
00000650 0.05103111 [6152] q(0, 129) sec = 25 nsec = 800000000
00000651 0.05106848 [6152] q(0, 130) sec = 26 nsec = 0
00000652 0.05110642 [6152] q(0, 131) sec = 26 nsec = 200000000
00000653 0.05114437 [6152] q(0, 132) sec = 26 nsec = 400000000
00000654 0.05118202 [6152] q(0, 133) sec = 26 nsec = 600000000
00000655 0.05122138 [6152] q(0, 134) sec = 26 nsec = 800000000
00000656 0.05125932 [6152] q(0, 135) sec = 27 nsec = 0
00000657 0.05129726 [6152] q(0, 136) sec = 27 nsec = 200000000
00000658 0.05133520 [6152] q(0, 137) sec = 27 nsec = 400000000
00000659 0.05137314 [6152] q(0, 138) sec = 27 nsec = 600000000
00000660 0.05141108 [6152] q(0, 139) sec = 27 nsec = 800000000
00000661 0.05144874 [6152] q(0, 140) sec = 28 nsec = 0
00000662 0.05148640 [6152] q(0, 141) sec = 28 nsec = 200000000
00000663 0.05152434 [6152] q(0, 142) sec = 28 nsec = 400000000
00000664 0.05156370 [6152] q(0, 143) sec = 28 nsec = 600000000
00000665 0.05160164 [6152] q(0, 144) sec = 28 nsec = 800000000
00000666 0.05163958 [6152] q(0, 145) sec = 29 nsec = 0
00000667 0.05167723 [6152] q(0, 146) sec = 29 nsec = 200000000
00000668 0.05171489 [6152] q(0, 147) sec = 29 nsec = 400000000
00000669 0.05175283 [6152] q(0, 148) sec = 29 nsec = 600000000
00000670 0.05179049 [6152] q(0, 149) sec = 29 nsec = 800000000
00000671 0.05182815 [6152] q(0, 150) sec = 30 nsec = 0
00000672 0.05186609 [6152] q(0, 151) sec = 30 nsec = 200000000
00000673 0.05190516 [6152] q(0, 152) sec = 30 nsec = 400000000
00000674 0.05194338 [6152] q(0, 153) sec = 30 nsec = 600000000
00000675 0.05198133 [6152] q(0, 154) sec = 30 nsec = 800000000
00000676 0.05201899 [6152] q(0, 155) sec = 31 nsec = 0
00000677 0.05205692 [6152] q(0, 156) sec = 31 nsec = 200000000
00000678 0.05209487 [6152] q(0, 157) sec = 31 nsec = 400000000
00000679 0.05213281 [6152] q(0, 158) sec = 31 nsec = 600000000
00000680 0.05217046 [6152] q(0, 159) sec = 31 nsec = 800000000
00000681 0.05220812 [6152] q(0, 160) sec = 32 nsec = 0
00000682 0.05224748 [6152] q(0, 161) sec = 32 nsec = 200000000
00000683 0.05228683 [6152] q(0, 162) sec = 32 nsec = 400000000
00000684 0.05232506 [6152] q(0, 163) sec = 32 nsec = 600000000
00000685 0.05236300 [6152] q(0, 164) sec = 32 nsec = 800000000
00000686 0.05240066 [6152] q(0, 165) sec = 33 nsec = 0
00000687 0.05243860 [6152] q(0, 166) sec = 33 nsec = 200000000
00000688 0.05247654 [6152] q(0, 167) sec = 33 nsec = 400000000
00000689 0.05251420 [6152] q(0, 168) sec = 33 nsec = 600000000
00000690 0.05255242 [6152] q(0, 169) sec = 33 nsec = 800000000
00000691 0.05259121 [6152] q(0, 170) sec = 34 nsec = 0
00000692 0.05262943 [6152] q(0, 171) sec = 34 nsec = 200000000
00000693 0.05266737 [6152] q(0, 172) sec = 34 nsec = 400000000
00000694 0.05270531 [6152] q(0, 173) sec = 34 nsec = 600000000
00000695 0.05274326 [6152] q(0, 174) sec = 34 nsec = 800000000
00000696 0.05278091 [6152] q(0, 175) sec = 35 nsec = 0
00000697 0.05281885 [6152] q(0, 176) sec = 35 nsec = 200000000
00000698 0.05285680 [6152] q(0, 177) sec = 35 nsec = 400000000
00000699 0.05289473 [6152] q(0, 178) sec = 35 nsec = 600000000
00000700 0.05293409 [6152] q(0, 179) sec = 35 nsec = 800000000
00000701 0.05297203 [6152] q(0, 180) sec = 36 nsec = 0
00000702 0.05300997 [6152] q(0, 181) sec = 36 nsec = 200000000
00000703 0.05304791 [6152] q(0, 182) sec = 36 nsec = 400000000
00000704 0.05308614 [6152] q(0, 183) sec = 36 nsec = 600000000
00000705 0.05312379 [6152] q(0, 184) sec = 36 nsec = 800000000
00000706 0.05316174 [6152] q(0, 185) sec = 37 nsec = 0
00000707 0.05319967 [6152] q(0, 186) sec = 37 nsec = 200000000
00000708 0.05323762 [6152] q(0, 187) sec = 37 nsec = 400000000
00000709 0.05327669 [6152] q(0, 188) sec = 37 nsec = 600000000
00000710 0.05331463 [6152] q(0, 189) sec = 37 nsec = 800000000
00000711 0.05335229 [6152] q(0, 190) sec = 38 nsec = 0
00000712 0.05339023 [6152] q(0, 191) sec = 38 nsec = 200000000
00000713 0.05342845 [6152] q(0, 192) sec = 38 nsec = 400000000
00000714 0.05346611 [6152] q(0, 193) sec = 38 nsec = 600000000
00000715 0.05350377 [6152] q(0, 194) sec = 38 nsec = 800000000
00000716 0.05354143 [6152] q(0, 195) sec = 39 nsec = 0
00000717 0.05357937 [6152] q(0, 196) sec = 39 nsec = 200000000
00000718 0.05361872 [6152] q(0, 197) sec = 39 nsec = 400000000
00000719 0.05365695 [6152] q(0, 198) sec = 39 nsec = 600000000
00000720 0.05369517 [6152] q(0, 199) sec = 39 nsec = 800000000
00000721 0.05373283 [6152] q(0, 200) sec = 40 nsec = 0
00000722 0.05377077 [6152] q(0, 201) sec = 40 nsec = 200000000
00000723 0.05380871 [6152] q(0, 202) sec = 40 nsec = 400000000
00000724 0.05384637 [6152] q(0, 203) sec = 40 nsec = 600000000
00000725 0.05388431 [6152] q(0, 204) sec = 40 nsec = 800000000
00000726 0.05392196 [6152] q(0, 205) sec = 41 nsec = 0
00000727 0.05396132 [6152] q(0, 206) sec = 41 nsec = 200000000
00000728 0.05399955 [6152] q(0, 207) sec = 41 nsec = 400000000
00000729 0.05403720 [6152] q(0, 208) sec = 41 nsec = 600000000
00000730 0.05407514 [6152] q(0, 209) sec = 41 nsec = 800000000
00000731 0.05411280 [6152] q(0, 210) sec = 42 nsec = 0
00000732 0.05415074 [6152] q(0, 211) sec = 42 nsec = 200000000
00000733 0.05418840 [6152] q(0, 212) sec = 42 nsec = 400000000
00000734 0.05422663 [6152] q(0, 213) sec = 42 nsec = 600000000
00000735 0.05426456 [6152] q(0, 214) sec = 42 nsec = 800000000
00000736 0.05430364 [6152] q(0, 215) sec = 43 nsec = 0
00000737 0.05434328 [6152] q(0, 216) sec = 43 nsec = 200000000
00000738 0.05438122 [6152] q(0, 217) sec = 43 nsec = 400000000
00000739 0.05441944 [6152] q(0, 218) sec = 43 nsec = 600000000
00000740 0.05445710 [6152] q(0, 219) sec = 43 nsec = 800000000
00000741 0.05449504 [6152] q(0, 220) sec = 44 nsec = 0
00000742 0.05453270 [6152] q(0, 221) sec = 44 nsec = 200000000
00000743 0.05457064 [6152] q(0, 222) sec = 44 nsec = 400000000
00000744 0.05460886 [6152] q(0, 223) sec = 44 nsec = 600000000
00000745 0.05464794 [6152] q(0, 224) sec = 44 nsec = 800000000
00000746 0.05468559 [6152] q(0, 225) sec = 45 nsec = 0
00000747 0.05472382 [6152] q(0, 226) sec = 45 nsec = 200000000
00000748 0.05476147 [6152] q(0, 227) sec = 45 nsec = 400000000
00000749 0.05479970 [6152] q(0, 228) sec = 45 nsec = 600000000
00000750 0.05483764 [6152] q(0, 229) sec = 45 nsec = 800000000
00000751 0.05487530 [6152] q(0, 230) sec = 46 nsec = 0
00000752 0.05491324 [6152] q(0, 231) sec = 46 nsec = 200000000
00000753 0.05495118 [6152] q(0, 232) sec = 46 nsec = 400000000
00000754 0.05499082 [6152] q(0, 233) sec = 46 nsec = 600000000
00000755 0.05502876 [6152] q(0, 234) sec = 46 nsec = 800000000
00000756 0.05506670 [6152] q(0, 235) sec = 47 nsec = 0
00000757 0.05510492 [6152] q(0, 236) sec = 47 nsec = 200000000
00000758 0.05514286 [6152] q(0, 237) sec = 47 nsec = 400000000
00000759 0.05518080 [6152] q(0, 238) sec = 47 nsec = 600000000
00000760 0.05521875 [6152] q(0, 239) sec = 47 nsec = 800000000
00000761 0.05525612 [6152] q(0, 240) sec = 48 nsec = 0
00000762 0.05529406 [6152] q(0, 241) sec = 48 nsec = 200000000
00000763 0.05533313 [6152] q(0, 242) sec = 48 nsec = 400000000
00000764 0.05537136 [6152] q(0, 243) sec = 48 nsec = 600000000
00000765 0.05540958 [6152] q(0, 244) sec = 48 nsec = 800000000
00000766 0.05544724 [6152] q(0, 245) sec = 49 nsec = 0
00000767 0.05548518 [6152] q(0, 246) sec = 49 nsec = 200000000
00000768 0.05552312 [6152] q(0, 247) sec = 49 nsec = 400000000
00000769 0.05556078 [6152] q(0, 248) sec = 49 nsec = 600000000
00000770 0.05559900 [6152] q(0, 249) sec = 49 nsec = 800000000
00000771 0.05563666 [6152] q(0, 250) sec = 50 nsec = 0
00000772 0.05567602 [6152] q(0, 251) sec = 50 nsec = 200000000
00000773 0.05571396 [6152] q(0, 252) sec = 50 nsec = 400000000
00000774 0.05575190 [6152] q(0, 253) sec = 50 nsec = 600000000
00000775 0.05578956 [6152] q(0, 254) sec = 50 nsec = 800000000
00000776 0.05582721 [6152] q(0, 255) sec = 51 nsec = 0
00000777 0.05586515 [6152] repeatSequenceStart_=0, repeatSize_ = 256
00000778 0.05590309 [6152] q(0, 256) sec = 51 nsec = 200000000
00000779 0.05594783 [6152] q(0, 257) sec = 51 nsec = 400000000
00000780 0.05598605 [6152] q(0, 258) sec = 51 nsec = 600000000
00000781 0.05602541 [6152] q(0, 259) sec = 51 nsec = 800000000
00000782 0.05606335 [6152] q(0, 260) sec = 52 nsec = 0
00000783 0.05610129 [6152] q(0, 261) sec = 52 nsec = 200000000
00000784 0.05613895 [6152] q(0, 262) sec = 52 nsec = 400000000
00000785 0.05617717 [6152] q(0, 263) sec = 52 nsec = 600000000
00000786 0.05621511 [6152] q(0, 264) sec = 52 nsec = 800000000
00000787 0.05625277 [6152] q(0, 265) sec = 53 nsec = 0
00000788 0.05629071 [6152] q(0, 266) sec = 53 nsec = 200000000
00000789 0.05632837 [6152] q(0, 267) sec = 53 nsec = 400000000
00000790 0.05636744 [6152] q(0, 268) sec = 53 nsec = 600000000
00000791 0.05640538 [6152] q(0, 269) sec = 53 nsec = 800000000
00000792 0.05644332 [6152] q(0, 270) sec = 54 nsec = 0
00000793 0.05648126 [6152] q(0, 271) sec = 54 nsec = 200000000
00000794 0.05651921 [6152] q(0, 272) sec = 54 nsec = 400000000
00000795 0.05655714 [6152] q(0, 273) sec = 54 nsec = 600000000
00000796 0.05659480 [6152] q(0, 274) sec = 54 nsec = 800000000
00000797 0.05663246 [6152] q(0, 275) sec = 55 nsec = 0
00000798 0.05667012 [6152] q(0, 276) sec = 55 nsec = 200000000
00000799 0.05670919 [6152] q(0, 277) sec = 55 nsec = 400000000
00000800 0.05674713 [6152] q(0, 278) sec = 55 nsec = 600000000
00000801 0.05678536 [6152] q(0, 279) sec = 55 nsec = 800000000
00000802 0.05682330 [6152] q(0, 280) sec = 56 nsec = 0
00000803 0.05686095 [6152] q(0, 281) sec = 56 nsec = 200000000
00000804 0.05689890 [6152] q(0, 282) sec = 56 nsec = 400000000
00000805 0.05693655 [6152] q(0, 283) sec = 56 nsec = 600000000
00000806 0.05697421 [6152] q(0, 284) sec = 56 nsec = 800000000
00000807 0.05701187 [6152] q(0, 285) sec = 57 nsec = 0
00000808 0.05705123 [6152] q(0, 286) sec = 57 nsec = 200000000
00000809 0.05708916 [6152] q(0, 287) sec = 57 nsec = 400000000
00000810 0.05712994 [6152] Server(done): sending 8 bytes to client
encoding <>
00000811 0.05728935 [6776] client(on_mpSocket_readyRead): Parsed as
00000812 0.05733295 [6776] In processModifyStreamAck
00000813 0.05751699 [6776] apply completed
00000814 9.41157436 [6776] In clearPortStats
00000815 9.41181374 [6776] client(CallMethod) sending 16 bytes
encoding <port_id {
00000816 9.41181374 [6776] id: 0
00000817 9.41181374 [6776] }
00000818 9.41181374 [6776] port_id {
00000819 9.41181374 [6776] id: 1
00000820 9.41181374 [6776] }
00000821 9.41181374 [6776] >
00000822 9.41193295 [6776] 0001000e00000008
00000823 9.41248798 [6152] In virtual void
MyService::clearStats(google::protobuf::RpcController*, const
OstProto::PortIdList*, OstProto::Ack*, google::protobuf::Closure*)
00000824 9.41263676 [6152] Server(done): sending 8 bytes to client
encoding <>
00000825 9.41305161 [6776] client(on_mpSocket_readyRead): Parsed as
00000826 9.41316605 [6776] In processClearStatsAck
00000827 9.41330910 [6776] RpcChannel: queueing method 13 since 14 is
pending; queued message = <port_id {
00000828 9.41330910 [6776] id: 0
00000829 9.41330910 [6776] }
00000830 9.41330910 [6776] port_id {
00000831 9.41330910 [6776] id: 1
00000832 9.41330910 [6776] }
00000833 9.41330910 [6776] >
00000834 9.41342163 [6776] pendingCallList size = 1
00000835 9.41355801 [6776] RpcChannel: executing queued method 13
<port_id {
00000836 9.41355801 [6776] id: 0
00000837 9.41355801 [6776] }
00000838 9.41355801 [6776] port_id {
00000839 9.41355801 [6776] id: 1
00000840 9.41355801 [6776] }
00000841 9.41355801 [6776] >
00000842 11.25945950 [6776] In startTx
00000843 11.25963306 [6776] client(CallMethod) sending 12 bytes
encoding <port_id {
00000844 11.25963306 [6776] id: 1
00000845 11.25963306 [6776] }
00000846 11.25963306 [6776] >
00000847 11.25975037 [6776] 0001000800000004
00000848 11.26031590 [6152] In virtual void
MyService::startTx(google::protobuf::RpcController*, const
OstProto::PortIdList*, OstProto::Ack*, google::protobuf::Closure*)
00000849 11.26069832 [6152] Server(done): sending 8 bytes to client
encoding <>
00000850 11.26099205 [6152] packetSequenceList_.size = 2
00000851 11.26114750 [6152] sendQ[0]: rptCnt = 78, rptSz = 1,
usecDelay = 200000
00000852 11.26121426 [6152] sendQ[0]: pkts = 256, usecDuration =
51000000
00000853 11.26133633 [6152] sendQ[1]: rptCnt = 1, rptSz = 1, usecDelay
= 0
00000854 11.26143837 [6152] sendQ[1]: pkts = 32, usecDuration =
6200000
00000855 11.26530743 [6776] client(on_mpSocket_readyRead): Parsed as
00000856 11.26543236 [6776] In processStartTxAck
00000857 20.14571762 [6776] In stopTx
00000858 20.14582062 [6776] client(CallMethod) sending 12 bytes
encoding <port_id {
00000859 20.14582062 [6776] id: 1
00000860 20.14582062 [6776] }
00000861 20.14582062 [6776] >
00000862 20.14586449 [6776] 0001000900000004
00000863 20.14610291 [6152] In virtual void
MyService::stopTx(google::protobuf::RpcController*, const
OstProto::PortIdList*, OstProto::Ack*, google::protobuf::Closure*)
00000864 20.14616585 [6152] Server(done): sending 8 bytes to client
encoding <>
00000865 20.14633942 [6776] client(on_mpSocket_readyRead): Parsed as
00000866 20.14638519 [6776] In processStopTxAck
00000867 24.77745438 [6776] In startTx
00000868 24.77753067 [6776] client(CallMethod) sending 12 bytes
encoding <port_id {
00000869 24.77753067 [6776] id: 1
00000870 24.77753067 [6776] }
00000871 24.77753067 [6776] >
00000872 24.77757835 [6776] 0001000800000004
00000873 24.77784538 [6152] In virtual void
MyService::startTx(google::protobuf::RpcController*, const
OstProto::PortIdList*, OstProto::Ack*, google::protobuf::Closure*)
00000874 24.77790070 [6152] Server(done): sending 8 bytes to client
encoding <>
00000875 24.77807617 [6776] client(on_mpSocket_readyRead): Parsed as
00000876 24.77812195 [6776] In processStartTxAck
00000877 29.58521652 [6776] In stopTx
00000878 29.58529663 [6776] client(CallMethod) sending 12 bytes
encoding <port_id {
00000879 29.58529663 [6776] id: 1
00000880 29.58529663 [6776] }
00000881 29.58529663 [6776] >
00000882 29.58534431 [6776] 0001000900000004
00000883 29.58564949 [6152] In virtual void
MyService::stopTx(google::protobuf::RpcController*, const
OstProto::PortIdList*, OstProto::Ack*, google::protobuf::Closure*)
00000884 29.58571434 [6152] Server(done): sending 8 bytes to client
encoding <>
00000885 29.58589554 [6776] client(on_mpSocket_readyRead): Parsed as
00000886 29.58593941 [6776] In processStopTxAck
00000887 33.04111481 [6776] In startTx
00000888 33.04118347 [6776] client(CallMethod) sending 12 bytes
encoding <port_id {
00000889 33.04118347 [6776] id: 1
00000890 33.04118347 [6776] }
00000891 33.04118347 [6776] >
00000892 33.04122925 [6776] 0001000800000004
00000893 33.04147720 [6152] In virtual void
MyService::startTx(google::protobuf::RpcController*, const
OstProto::PortIdList*, OstProto::Ack*, google::protobuf::Closure*)
00000894 33.04153442 [6152] Server(done): sending 8 bytes to client
encoding <>
00000895 33.04170609 [6776] client(on_mpSocket_readyRead): Parsed as
00000896 33.04175186 [6776] In processStartTxAck
00000897 35.50502396 [6776] In stopTx
00000898 35.50510406 [6776] client(CallMethod) sending 12 bytes
encoding <port_id {
00000899 35.50510406 [6776] id: 1
00000900 35.50510406 [6776] }
00000901 35.50510406 [6776] >
00000902 35.50514984 [6776] 0001000900000004
00000903 35.50544357 [6152] In virtual void
MyService::stopTx(google::protobuf::RpcController*, const
OstProto::PortIdList*, OstProto::Ack*, google::protobuf::Closure*)
00000904 35.50550461 [6152] Server(done): sending 8 bytes to client
encoding <>
00000905 35.50568008 [6776] client(on_mpSocket_readyRead): Parsed as
00000906 35.50572586 [6776] In processStopTxAck

rper...@yahoo.com

unread,
Feb 21, 2012, 4:00:30 PM2/21/12
to ostinato
I posted the contents of the log file a few hours ago but maybe the
post is too big for the system as it has not appeared. I cannot see an
obvious way to attach a file. Srivats - I can email the file to you if
you can supply an email address.

On Feb 21, 8:38 am, "rperi...@yahoo.com" <rperi...@yahoo.com> wrote:

Srivats P

unread,
Feb 22, 2012, 8:43:04 AM2/22/12
to rper...@yahoo.com, ostinato
Since you are not a member of the mailing list (why not? it's free to
join!), your messages need to be moderated before being posted to the
list - which is why the delay.

I will look at the log file to see if I can identify the problem, but
meanwhile if you could send a sample stream with which you can
recreate the problem, it would be great.

Not sure why you are not able to attach a file - are you using the
googlegroups web interface? you can just send an email to ostinato at
googlegroups dot com to post to this list and attach any files to the
email.

Srivats

rper...@yahoo.com

unread,
Feb 22, 2012, 10:56:52 AM2/22/12
to ostinato
Sure I can supply the stream file that I'm using. Not sure what a
Google groups web interface is - unlike many online forums the web
interface I have on here only has "send" and "discard" buttons -
nothing else.

I will send the stream file via email.

Srivats P

unread,
Mar 9, 2012, 11:48:38 AM3/9/12
to rper...@yahoo.com, ostinato
I've committed a fix - please build from source and try.

Srivats

Reply all
Reply to author
Forward
0 new messages