2013-03-08 10:55:23.157 DBG:core:receive_msg: cleaning up
2013-03-08 10:55:23.157 DBG:core:free_sdp: _sdp = 0x2b3752c71558
2013-03-08 10:55:23.157 DBG:core:free_sdp: sdp = 0x2b3752c73150
2013-03-08 10:55:23.157 DBG:core:free_sdp: session = 0x2b3752c729a0
2013-03-08 10:55:23.172 DBG:core:parse_msg: SIP Reply  (status):
2013-03-08 10:55:23.172 DBG:core:parse_msg:  version: <SIP/2.0>
2013-03-08 10:55:23.172 DBG:core:parse_msg:  status:  <200>
2013-03-08 10:55:23.172 DBG:core:parse_msg:  reason:  <OK>
2013-03-08 10:55:23.172 DBG:core:parse_headers: flags=2
2013-03-08 10:55:23.172 DBG:core:parse_to_param: tag=b34d60732fad5790i0
2013-03-08 10:55:23.172 DBG:core:parse_to: end of header reached, state=29
2013-03-08 10:55:23.172 DBG:core:parse_to: display={}, ruri={sip:filipa.porto.geo5@10.112.136.161}
2013-03-08 10:55:23.172 DBG:core:get_hdr_field: <To> [63]; uri=[sip:filipa.porto.geo5@10.112.136.161]
2013-03-08 10:55:23.172 DBG:core:get_hdr_field: to body [<sip:filipa.porto.geo5@10.112.136.161>]
2013-03-08 10:55:23.172 DBG:core:get_hdr_field: cseq <CSeq>: <3> <INVITE>
2013-03-08 10:55:23.172 DBG:core:parse_via_param: found param type 232, <branch> = <z9hG4bKc8a.90336d
27.0>; state=16
2013-03-08 10:55:23.172 DBG:core:parse_via: end of header reached, state=5
2013-03-08 10:55:23.172 DBG:core:parse_headers: via found, flags=2
2013-03-08 10:55:23.172 DBG:core:parse_headers: this is the first via
2013-03-08 10:55:23.172 DBG:core:receive_msg: After parse_msg...
2013-03-08 10:55:23.172 DBG:core:parse_headers: flags=2000
2013-03-08 10:55:23.172 DBG:core:get_hdr_field: content_length=208
2013-03-08 10:55:23.172 DBG:core:parse_headers: flags=ffffffffffffffff
2013-03-08 10:55:23.172 DBG:core:get_hdr_field: found end of header
2013-03-08 10:55:23.172 DBG:core:decode_mime_type: Decoding MIME type for:[application/sdp]
2013-03-08 10:55:23.172 INFO:xcoder_b2b:parse_200OK: Parse 200OK
2013-03-08 10:55:23.172 DBG:core:parse_headers: flags=ffffffffffffffff
2013-03-08 10:55:23.172 DBG:core:parse_headers: flags=38
2013-03-08 10:55:23.172 DBG:core:parse_to_param: tag=af4bc0b23adc3f1659f72785ed6d47c4
2013-03-08 10:55:23.172 DBG:core:parse_to: end of header reached, state=29
2013-03-08 10:55:23.172 DBG:core:parse_to: display={JP}, ruri={sip:transcoder@10.112.136.163}
2013-03-08 10:55:23.185 DBG:b2b_logic:b2bl_parse_key: hash_index = [272]  - local_index= [0]
2013-03-08 10:55:23.185 DBG:b2b_entities:b2b_parse_key: hash_index = [321]  - local_index= [128]
2013-03-08 10:55:23.185 DBG:b2b_entities:b2b_search_htable_next_dlg: entering with start=(nil), table
=0x2b37554c3a28, hash=321, label=128
2013-03-08 10:55:23.185 DBG:b2b_entities:b2b_search_htable_next_dlg: searching  callid 16[3c7fcf068e1
4d72f]
2013-03-08 10:55:23.185 DBG:b2b_entities:b2b_search_htable_next_dlg: searching   totag 11[B2B.321.128
]
2013-03-08 10:55:23.185 DBG:b2b_entities:b2b_search_htable_next_dlg: searching fromtag 8[f2449a3c]
2013-03-08 10:55:23.185 DBG:b2b_entities:b2b_search_htable_next_dlg: Match for server dlg [0x2b3759fe
b7a0] dlg->uas_tran=[0x2b3759ff3918]
2013-03-08 10:55:23.185 DBG:b2b_entities:b2b_send_request: Send request [BYE] for entity type [0] for
 dlg[0x2b3759feb7a0]->[B2B.321.128]
2013-03-08 10:55:23.185 DBG:tm:t_uac: next_hop=<sip:transcoder@10.112.136.163:5060>
2013-03-08 10:55:23.185 DBG:core:mk_proxy: doing DNS lookup...
2013-03-08 10:55:23.185 DBG:tm:dlg2hash: 39973
2013-03-08 10:55:23.185 DBG:tm:print_request_uri: sip:transcoder@10.112.136.163:5060
2013-03-08 10:55:23.185 DBG:tm:t_uac: building sip_msg from buffer
2013-03-08 10:55:23.185 DBG:core:parse_msg: SIP Request:
2013-03-08 10:55:23.185 DBG:core:parse_msg:  method:  <BYE>
2013-03-08 10:55:23.185 DBG:core:parse_msg:  uri:     <sip:transcoder@10.112.136.163:5060>
2013-03-08 10:55:23.185 DBG:core:parse_msg:  version: <SIP/2.0>
2013-03-08 10:55:23.185 DBG:core:parse_headers: flags=2
2013-03-08 10:55:23.186 DBG:core:parse_via_param: found param type 232, <branch> = <z9hG4bK52c9.bac78
635.0>; state=16
2013-03-08 10:55:23.186 DBG:core:parse_via: end of header reached, state=5
2013-03-08 10:55:23.186 DBG:core:parse_headers: via found, flags=2
2013-03-08 10:55:23.186 DBG:core:parse_headers: this is the first via
2013-03-08 10:55:23.186 DBG:core:mk_proxy: doing DNS lookup...
2013-03-08 10:55:23.186 DBG:tm:set_timer: relative timeout is 500000
2013-03-08 10:55:23.186 DBG:tm:insert_timer_unsafe: [4]: 0x2b3759ff8c70 (28200000)
2013-03-08 10:55:23.186 DBG:tm:set_timer: relative timeout is 30
2013-03-08 10:55:23.186 DBG:tm:insert_timer_unsafe: [0]: 0x2b3759ff8ca0 (57)
2013-03-08 10:55:23.186 DBG:b2b_entities:b2b_parse_key: hash_index = [272]  - local_index= [264774]
2013-03-08 10:55:23.186 DBG:b2b_entities:b2b_search_htable_next_dlg: entering with start=(nil), table
=0x2b37554c5a40, hash=272, label=264774
2013-03-08 10:55:23.186 DBG:b2b_entities:b2b_search_htable_next_dlg: searching  callid 14[B2B.272.264
774]
2013-03-08 10:55:23.186 DBG:b2b_entities:b2b_search_htable_next_dlg: searching   totag 32[af4bc0b23ad
c3f1659f72785ed6d47c4]
2013-03-08 10:55:23.186 DBG:b2b_entities:b2b_search_htable_next_dlg: searching fromtag 18[b34d60732fa
d5790i0]
2013-03-08 10:55:23.186 DBG:b2b_entities:b2b_send_request: Send request [BYE] for entity type [1] for
 dlg[0x2b3759ff1018]->[B2B.272.264774]
2013-03-08 10:55:23.186 DBG:b2b_entities:b2b_client_build_dlg: Rem_target = sip:filipa.porto.geo5@10.
112.136.161:5060
2013-03-08 10:55:23.186 DBG:b2b_entities:b2b_client_build_dlg: send sock= 10.112.137.205
2013-03-08 10:55:23.186 DBG:tm:t_uac: next_hop=<sip:filipa.porto.geo5@10.112.136.161:5060>
2013-03-08 10:55:23.186 DBG:core:mk_proxy: doing DNS lookup...
2013-03-08 10:55:23.186 DBG:tm:dlg2hash: 2697
2013-03-08 10:55:23.186 DBG:tm:print_request_uri: sip:filipa.porto.geo5@10.112.136.161:5060
2013-03-08 10:55:23.186 DBG:tm:t_uac: building sip_msg from buffer
2013-03-08 10:55:23.186 DBG:core:parse_msg: SIP Request:
2013-03-08 10:55:23.186 DBG:core:parse_msg:  method:  <BYE>
2013-03-08 10:55:23.186 DBG:core:parse_msg:  uri:     <sip:filipa.porto.geo5@10.112.136.161:5060>
2013-03-08 10:55:23.186 DBG:core:parse_msg:  version: <SIP/2.0>
2013-03-08 10:55:23.186 DBG:core:parse_headers: flags=2
2013-03-08 10:55:23.186 DBG:core:parse_via_param: found param type 232, <branch> = <z9hG4bK98a.e4852f67.0>; state=16
2013-03-08 10:55:23.186 DBG:core:parse_via: end of header reached, state=5
2013-03-08 10:55:23.186 DBG:core:parse_headers: via found, flags=2
2013-03-08 10:55:23.186 DBG:core:parse_headers: this is the first via
2013-03-08 10:55:23.186 DBG:core:mk_proxy: doing DNS lookup...
2013-03-08 10:55:23.186 DBG:tm:set_timer: relative timeout is 500000
2013-03-08 10:55:23.186 DBG:tm:insert_timer_unsafe: [4]: 0x2b3759ffa6b8 (28200000)
2013-03-08 10:55:23.186 DBG:tm:set_timer: relative timeout is 30
2013-03-08 10:55:23.186 DBG:tm:insert_timer_unsafe: [0]: 0x2b3759ffa6e8 (57)
2013-03-08 10:55:23.186 DBG:b2b_logic:b2b_mark_todel: 0x2b3759fe8f18
2013-03-08 10:55:23.186 DBG:core:forward_reply: found module tm, passing reply to it
2013-03-08 10:55:23.186 DBG:tm:t_check: start=0xffffffffffffffff
2013-03-08 10:55:23.186 DBG:core:parse_headers: flags=22
2013-03-08 10:55:23.186 DBG:core:parse_headers: flags=8
2013-03-08 10:55:23.186 DBG:tm:t_reply_matching: hash 2700 label 1926640393 branch 0
2013-03-08 10:55:23.186 DBG:tm:t_reply_matching: REF_UNSAFE:[0x2b3759ff67d0] after is 2
2013-03-08 10:55:23.186 DBG:tm:t_reply_matching: reply matched (T=0x2b3759ff67d0)!
2013-03-08 10:55:23.186 DBG:core:parse_headers: flags=8
2013-03-08 10:55:23.186 DBG:tm:t_check: end=0x2b3759ff67d0
2013-03-08 10:55:23.186 DBG:tm:reply_received: org. status uas=0, uac[0]=0 local=2 is_invite=1)
2013-03-08 10:55:23.186 DBG:tm:t_should_relay_response: T_code=0, new_code=200
2013-03-08 10:55:23.186 DBG:tm:local_reply: branch=0, save=0, winner=0
2013-03-08 10:55:23.186 DBG:tm:local_reply: local transaction completed
2013-03-08 10:55:23.186 DBG:tm:run_trans_callbacks: trans=0x2b3759ff67d0, callback type 256, id 0 entered
2013-03-08 10:55:23.186 DBG:b2b_entities:b2b_tm_cback: tm [0x2b3759ff67d0] notification cb for [200] reply
2013-03-08 10:55:23.186 DBG:b2b_entities:b2b_parse_key: hash_index = [272]  - local_index= [264774]
2013-03-08 10:55:23.186 DBG:core:parse_headers: flags=ffffffffffffffff
2013-03-08 10:55:23.186 DBG:b2b_entities:b2b_search_htable_next_dlg: entering with start=(nil), table=0x2b37554c5a40, hash=272, label=264774
2013-03-08 10:55:23.186 DBG:b2b_entities:b2b_search_htable_next_dlg: searching  callid 14[B2B.272.264774]
2013-03-08 10:55:23.186 DBG:b2b_entities:b2b_search_htable_next_dlg: searching   totag 32[af4bc0b23adc3f1659f72785ed6d47c4]
2013-03-08 10:55:23.186 DBG:b2b_entities:b2b_search_htable_next_dlg: searching fromtag 18[b34d60732fad5790i0]
2013-03-08 10:55:23.186 DBG:b2b_entities:b2b_tm_cback: Received reply [200] for dialog [0x2b3759ff1018], method [INVITE]
2013-03-08 10:55:23.186 DBG:tm:t_unref_cell: UNREF_UNSAFE: [0x2b3759ff67d0] after is 1
2013-03-08 10:55:23.186 DBG:b2b_entities:b2b_tm_cback: dlg=[0x2b3759ff1018], uac_tran=NULL
2013-03-08 10:55:23.186 DBG:b2b_entities:b2b_tm_cback: Received 200 OK after the call was terminated B2B.272.264774
2013-03-08 10:55:23.186 DBG:b2b_entities:b2b_send_req: start type=1
2013-03-08 10:55:23.186 DBG:b2b_entities:b2b_client_build_dlg: Rem_target = sip:filipa.porto.geo5@10.112.136.161:5060
2013-03-08 10:55:23.186 DBG:b2b_entities:b2b_client_build_dlg: send sock= 10.112.137.205
2013-03-08 10:55:23.186 DBG:tm:t_uac: next_hop=<sip:filipa.porto.geo5@10.112.136.161:5060>
2013-03-08 10:55:23.186 DBG:core:mk_proxy: doing DNS lookup...
2013-03-08 10:55:23.186 DBG:tm:dlg2hash: 2700
2013-03-08 10:55:23.186 DBG:tm:print_request_uri: sip:filipa.porto.geo5@10.112.136.161:5060
2013-03-08 10:55:23.186 DBG:tm:t_uac: building sip_msg from buffer
2013-03-08 10:55:23.186 DBG:core:parse_msg: SIP Request:
2013-03-08 10:55:23.186 DBG:core:parse_msg:  method:  <ACK>
2013-03-08 10:55:23.186 DBG:core:parse_msg:  uri:     <sip:filipa.porto.geo5@10.112.136.161:5060>
2013-03-08 10:55:23.186 DBG:core:parse_msg:  version: <SIP/2.0>
2013-03-08 10:55:23.186 DBG:core:parse_headers: flags=2
2013-03-08 10:55:23.186 DBG:core:parse_via_param: found param type 232, <branch> = <z9hG4bKc8a.a0336d27.0>; state=16
2013-03-08 10:55:23.186 DBG:core:parse_via: end of header reached, state=5
2013-03-08 10:55:23.187 DBG:core:parse_headers: via found, flags=2
2013-03-08 10:55:23.187 DBG:core:parse_headers: this is the first via
2013-03-08 10:55:23.187 DBG:core:mk_proxy: doing DNS lookup...
2013-03-08 10:55:23.187 DBG:tm:cleanup_uac_timers: RETR/FR timers reset
2013-03-08 10:55:23.187 DBG:tm:insert_timer_unsafe: [2]: 0x2b3759ffc0f8 (32)
2013-03-08 10:55:23.187 DBG:b2b_entities:b2b_send_req: start type=1
2013-03-08 10:55:23.187 DBG:b2b_entities:b2b_client_build_dlg: Rem_target = sip:filipa.porto.geo5@10.112.136.161:5060
2013-03-08 10:55:23.187 DBG:b2b_entities:b2b_client_build_dlg: send sock= 10.112.137.205
2013-03-08 10:55:23.187 DBG:tm:t_uac: next_hop=<sip:filipa.porto.geo5@10.112.136.161:5060>
2013-03-08 10:55:23.187 DBG:core:mk_proxy: doing DNS lookup...
2013-03-08 10:55:23.187 DBG:tm:dlg2hash: 2698
2013-03-08 10:55:23.187 DBG:tm:print_request_uri: sip:filipa.porto.geo5@10.112.136.161:5060
2013-03-08 10:55:23.187 DBG:tm:t_uac: building sip_msg from buffer
2013-03-08 10:55:23.187 DBG:core:parse_msg: SIP Request:
2013-03-08 10:55:23.187 DBG:core:parse_msg:  method:  <BYE>
2013-03-08 10:55:23.187 DBG:core:parse_msg:  uri:     <sip:filipa.porto.geo5@10.112.136.161:5060>
2013-03-08 10:55:23.187 DBG:core:parse_msg:  version: <SIP/2.0>
2013-03-08 10:55:23.187 DBG:core:parse_headers: flags=2
2013-03-08 10:55:23.187 DBG:core:parse_via_param: found param type 232, <branch> = <z9hG4bKa8a.caa14253.0>; state=16
2013-03-08 10:55:23.187 DBG:core:parse_via: end of header reached, state=5
2013-03-08 10:55:23.187 DBG:core:parse_headers: via found, flags=2
2013-03-08 10:55:23.187 DBG:core:parse_headers: this is the first via
2013-03-08 10:55:23.187 DBG:core:mk_proxy: doing DNS lookup...
2013-03-08 10:55:23.187 DBG:tm:set_timer: relative timeout is 500000
2013-03-08 10:55:23.187 DBG:tm:insert_timer_unsafe: [4]: 0x2b3759ffde58 (28200000)
2013-03-08 10:55:23.187 DBG:tm:set_timer: relative timeout is 30
2013-03-08 10:55:23.187 DBG:tm:insert_timer_unsafe: [0]: 0x2b3759ffde88 (57)
2013-03-08 10:55:23.187 DBG:b2b_logic:b2bl_parse_key: hash_index = [272]  - local_index= [0]
2013-03-08 10:55:23.187 DBG:core:parse_headers: flags=ffffffffffffffff
2013-03-08 10:55:23.187 DBG:core:parse_headers: flags=ffffffffffffffff
2013-03-08 10:55:23.187 DBG:b2b_logic:b2b_apply_body_lumps: *** len = 196
2013-03-08 10:55:23.187 DBG:b2b_logic:b2b_apply_body_lumps: offset = 196, s_offset=639
2013-03-08 10:55:23.187 DBG:b2b_logic:b2b_apply_body_lumps: new_body= [v=0
o=- 49896 49897 IN IP4 10.112.137.205
s=-
c=IN IP4 10.112.137.205
t=0 0
m=audio 25006 RTP/AVP 8 101
a=recvonly
a=rtpmap:8 pcma/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
], len=196
2013-03-08 10:55:23.187 DBG:b2b_logic:b2b_apply_body_lumps: last chars 10 - 13
2013-03-08 10:55:23.187 DBG:b2b_logic:b2b_logic_notify: b2b_entities notification cb for [272.0] with entity [B2B.272.264774]
2013-03-08 10:55:23.187 DBG:core:parse_headers: flags=ffffffffffffffff
2013-03-08 10:55:23.187 DBG:b2b_logic:b2bl_search_entity: Key [B2B.272.264774]
2013-03-08 10:55:23.187 DBG:b2b_logic:b2b_logic_notify_reply: b2b_entity key = B2B.272.264774
2013-03-08 10:55:23.187 DBG:b2b_logic:b2b_logic_notify_reply: entity [B2B.272.264774] is disconnected
2013-03-08 10:55:23.187 DBG:b2b_logic:b2bl_delete_entity: delete entity [0x2b3759ff0e98]->[B2B.272.264774] from tuple [272.0]
2013-03-08 10:55:23.187 DBG:b2b_entities:b2b_parse_key: hash_index = [272]  - local_index= [264774]
2013-03-08 10:55:23.187 DBG:b2b_entities:b2b_search_htable_next_dlg: entering with start=(nil), table=0x2b37554c5a40, hash=272, label=264774
2013-03-08 10:55:23.187 DBG:b2b_entities:b2b_search_htable_next_dlg: searching  callid 14[B2B.272.264774]
2013-03-08 10:55:23.187 DBG:b2b_entities:b2b_search_htable_next_dlg: searching   totag 32[af4bc0b23adc3f1659f72785ed6d47c4]
2013-03-08 10:55:23.187 DBG:b2b_entities:b2b_search_htable_next_dlg: searching fromtag 18[b34d60732fad5790i0]
2013-03-08 10:55:23.187 DBG:b2b_entities:b2b_entity_delete: Deleted dlg [0x2b3759ff1018]->[B2B.272.264774] with dlginfo [0x2b3759fef230]
2013-03-08 10:55:23.187 INFO:b2b_logic:b2bl_delete_entity: delete tuple [272.0], entity [B2B.272.264774]
2013-03-08 10:55:23.187 [0x2b3759fe8f18]->[272.0] to_del=[1] lifetime=[57] bridge_entities[0x2b3759fef018][(nil)][(nil)]
2013-03-08 10:55:23.187 .type=[0] index=[0] [0x2b3759fef018]->[B2B.321.128] state=1 no=0 dlginfo=[0x2b3759fef120] peer=[(nil)] prev:next=[(nil)][(nil)]
2013-03-08 10:55:23.187 ..........dlginfo=[0x2b3759fef120]->[3c7fcf068e14d72f][f2449a3c][B2B.321.128]
2013-03-08 10:55:23.187 .type=[0] index=[0] [0x2b3759fef018] peer=[(nil)] prev:next=[(nil)][(nil)]
2013-03-08 10:55:23.187 DBG:tm:cleanup_uac_timers: RETR/FR timers reset
2013-03-08 10:55:23.187 DBG:tm:insert_timer_unsafe: [2]: 0x2b3759ff6850 (32)
2013-03-08 10:55:23.187 DBG:tm:t_unref: UNREF_UNSAFE: [0x2b3759ff67d0] after is 0
2013-03-08 10:55:23.187 DBG:core:destroy_avp_list: destroying list (nil)
2013-03-08 10:55:23.187 DBG:core:receive_msg: cleaning up
2013-03-08 10:55:23.198 DBG:core:parse_msg: SIP Reply  (status):
2013-03-08 10:55:23.198 DBG:core:parse_msg:  version: <SIP/2.0>
2013-03-08 10:55:23.198 DBG:core:parse_msg:  status:  <200>