[Freeswitch-users] T38 fax. FS fails after reINVITE

Vladimir Getmanshchuk vladget at gmail.com
Sun Jun 1 08:32:45 MSD 2014


I've tested faxes with FS 1.4.5. And still got no success.
FS(fax originator)  ---> tested FS ----> SWITCH WHAT SUPPORT T38.

FS reply 100 trying after reINVITE and that's it, but should answer with
200 OK..
Please advice how to get it work.

my sip trace and sofia trace:

recv 1016 bytes from udp/[11.11.11.11]:5080 at 23:41:30.861560:
   ------------------------------------------------------------------------
   INVITE sip:2222222222 at 22.22.22.22:5070 SIP/2.0
   Via: SIP/2.0/UDP 11.11.11.11:5080;rport;branch=z9hG4bKNpj7NtK5yQjyQ
   Max-Forwards: 70
   From: "+111111111111" <sip:+111111111111 at 11.11.11.11>;tag=H53g29BtQBSKj
   To: <sip:2222222222 at 22.22.22.22:5070>
   Call-ID: eeda303b-63bf-1232-feb0-f2eff32e29d0
   CSeq: 60453781 INVITE
   Contact: <sip:mod_sofia at 11.11.11.11:5080>
   User-Agent: FS
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE,
REGISTER, REFER, NOTIFY
   Supported: timer, path, replaces
   Allow-Events: talk, hold, conference, refer
   Content-Type: application/sdp
   Content-Disposition: session
   Content-Length: 225
   X-FS-Support: update_display,send_info
   Remote-Party-ID: "+111111111111" <sip:+111111111111 at 11.11.11.11
>;party=calling;screen=yes;privacy=off

   v=0
   o=FreeSWITCH 1401562482 1401562483 IN IP4 11.11.11.11
   s=FreeSWITCH
   c=IN IP4 11.11.11.11
   t=0 0
   m=audio 17208 RTP/AVP 8 101 13
   a=rtpmap:8 PCMA/8000
   a=rtpmap:101 telephone-event/8000
   a=fmtp:101 0-16
   a=ptime:20
   ------------------------------------------------------------------------
tport.c:3023 tport_deliver() tport_deliver(0x1d12180): msg 0x1ca3590 (1016
bytes) from udp/11.11.11.11:5070/sip next=(nil)
nta.c:2880 agent_recv_request() nta: received INVITE
sip:2222222222 at 22.22.22.22:5070 SIP/2.0 (CSeq 60453781)
nta.c:3246 agent_aliases() nta: canonizing sip:2222222222 at 22.22.22.22:5070
with contact
nta.c:3085 agent_recv_request() nta: INVITE (60453781) going to a default
leg
nta.c:1350 set_timeout() nta: timer set to 200 ms
nua_server.c:102 nua_stack_process_request() nua:
nua_stack_process_request: entering
nua_stack.c:899 nh_create() nua: nh_create: entering
nua_common.c:108 nh_create_handle() nua: nh_create_handle: entering
nua_params.c:480 nua_stack_set_params() nua: nua_stack_set_params: entering
soa.c:280 soa_clone() soa_clone(static::0x1d0fd90, 0x1cb0640, 0x1d39800)
called
soa.c:403 soa_set_params() soa_set_params(static::0x1d17170, ...) called
nta.c:4415 nta_leg_tcreate() nta_leg_tcreate(0x1d17950)
soa.c:1302 soa_init_offer_answer() soa_init_offer_answer(static::0x1d17170)
called
soa.c:1171 soa_set_remote_sdp() soa_set_remote_sdp(static::0x1d17170,
(nil), 0x1d13e67, 225) called
nua_dialog.c:338 nua_dialog_usage_add() nua(0x1d39800): adding session usage
tport.c:3257 tport_tsend() tport_tsend(0x1d12180) tpn = UDP/11.11.11.11:5080
tport.c:4046 tport_resolve() tport_resolve addrinfo = 11.11.11.11:5080
tport.c:4680 tport_by_addrinfo() tport_by_addrinfo(0x1d12180): not found by
name UDP/11.11.11.11:5080
tport.c:3594 tport_vsend() tport_vsend(0x1d12180): 324 bytes of 324 to udp/
11.11.11.11:5080
tport.c:3492 tport_send_msg() tport_vsend returned 324
send 324 bytes to udp/[11.11.11.11]:5080 at 23:41:30.862101:
   ------------------------------------------------------------------------
   SIP/2.0 100 Trying
   Via: SIP/2.0/UDP 11.11.11.11:5080;rport=5080;branch=z9hG4bKNpj7NtK5yQjyQ
   From: "+111111111111" <sip:+111111111111 at 11.11.11.11>;tag=H53g29BtQBSKj
   To: <sip:2222222222 at 22.22.22.22:5070>
   Call-ID: eeda303b-63bf-1232-feb0-f2eff32e29d0
   CSeq: 60453781 INVITE
   User-Agent: FS
   Content-Length: 0

   ------------------------------------------------------------------------
nta.c:6789 incoming_reply() nta: sent 100 Trying for INVITE (60453781)
nua_stack.c:271 nua_stack_event() nua(0x1d39800): event i_invite 100 Trying
nua_session.c:4137 signal_call_state_change() nua(0x1d39800): call state
changed: init -> received, received offer
soa.c:1098 soa_get_remote_sdp() soa_get_remote_sdp(static::0x1d17170,
[0x7f5303ffe598], [0x7f5303ffe5a0], [(nil)]) called
nua_stack.c:271 nua_stack_event() nua(0x1d39800): event i_state 100 Trying
nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
nua.c:342 nua_handle_bind() nua: nua_handle_bind: entering
nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
nua_common.c:108 nh_create_handle() nua: nh_create_handle: entering
nua.c:342 nua_handle_bind() nua: nua_handle_bind: entering
nua.c:633 nua_invite() nua: nua_invite: entering
nua_stack.c:529 nua_signal() nua(0x7f53700147d0): sent signal r_invite
nua_stack.c:569 nua_stack_signal() nua(0x7f53700147d0): recv signal r_invite
nua_params.c:480 nua_stack_set_params() nua: nua_stack_set_params: entering
soa.c:280 soa_clone() soa_clone(static::0x7f5334001930, 0x7f5334001130,
0x7f53700147d0) called
soa.c:403 soa_set_params() soa_set_params(static::0x7f533401eed0, ...)
called
soa.c:403 soa_set_params() soa_set_params(static::0x7f533401eed0, ...)
called
soa.c:1052 soa_set_user_sdp() soa_set_user_sdp(static::0x7f533401eed0,
(nil), 0x7f53700123f3, -1) called
soa.c:890 soa_set_capability_sdp()
soa_set_capability_sdp(static::0x7f533401eed0, (nil), 0x7f53700123f3, -1)
called
nua_dialog.c:338 nua_dialog_usage_add() nua(0x7f53700147d0): adding session
usage
nta.c:4415 nta_leg_tcreate() nta_leg_tcreate(0x7f5334018320)
soa.c:1302 soa_init_offer_answer()
soa_init_offer_answer(static::0x7f533401eed0) called
soa.c:1426 soa_generate_offer() soa_generate_offer(static::0x7f533401eed0,
0) called
soa_static.c:1137 offer_answer_step()
soa_static_offer_answer_action(0x7f533401eed0, soa_generate_offer): called
soa_static.c:1168 offer_answer_step() soa_static(0x7f533401eed0,
soa_generate_offer): generating local description
soa_static.c:1196 offer_answer_step() soa_static(0x7f533401eed0,
soa_generate_offer): upgrade with local description
soa_static.c:1020 soa_sdp_mode_set() soa_sdp_mode_set(0x7f537d8139d0,
(nil), ""): called
soa_static.c:1425 offer_answer_step() soa_static(0x7f533401eed0,
soa_generate_offer): storing local description
soa.c:1270 soa_get_local_sdp() soa_get_local_sdp(static::0x7f533401eed0,
[(nil)], [0x7f537d813b40], [0x7f537d813b4c]) called
nta.c:2665 nta_tpn_by_url() nta: selecting scheme sip
tport.c:3257 tport_tsend() tport_tsend(0x7f5334004450) tpn = UDP/
33.33.33.33:5060
tport.c:4046 tport_resolve() tport_resolve addrinfo = 33.33.33.33:5060
tport.c:4680 tport_by_addrinfo() tport_by_addrinfo(0x7f5334004450): not
found by name UDP/33.33.33.33:5060
tport.c:3594 tport_vsend() tport_vsend(0x7f5334004450): 1010 bytes of 1010
to udp/33.33.33.33:5060
tport.c:3492 tport_send_msg() tport_vsend returned 1010
send 1010 bytes to udp/[33.33.33.33]:5060 at 23:41:30.865276:
   ------------------------------------------------------------------------
   INVITE sip:002222222222 at 33.33.33.33 SIP/2.0
   Via: SIP/2.0/UDP 22.22.22.22:5080;rport;branch=z9hG4bKX94Z74KNte97S
   Max-Forwards: 69
   From: "+111111111111" <sip:+111111111111 at 22.22.22.22>;tag=vg4Z33QvjBg5c
   To: <sip:002222222222 at 33.33.33.33>
   Call-ID: eedb70c7-63bf-1232-47b6-02082072a337
   CSeq: 60453781 INVITE
   Contact: <sip:mod_sofia at 22.22.22.22:5080>
   User-Agent: FS
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE,
REGISTER, REFER, NOTIFY
   Supported: timer, path, replaces
   Allow-Events: talk, hold, conference, refer
   Content-Type: application/sdp
   Content-Disposition: session
   Content-Length: 225
   X-FS-Support: update_display,send_info
   Remote-Party-ID: "+111111111111" <sip:+111111111111 at 22.22.22.22
>;party=calling;screen=yes;privacy=off

   v=0
   o=FreeSWITCH 1401556470 1401556471 IN IP4 22.22.22.22
   s=FreeSWITCH
   c=IN IP4 22.22.22.22
   t=0 0
   m=audio 23220 RTP/AVP 8 101 13
   a=rtpmap:8 PCMA/8000
   a=rtpmap:101 telephone-event/8000
   a=fmtp:101 0-16
   a=ptime:20
   ------------------------------------------------------------------------
nta.c:8302 outgoing_send() nta: sent INVITE (60453781) to UDP/
33.33.33.33:5060
tport.c:4160 tport_pend() tport_pend(0x7f5334004450): pending
0x7f5334029a40 for udp/22.22.22.22:5080 (already 0)
nta.c:1350 set_timeout() nta: timer set to 32000 ms
nta.c:1348 set_timeout() nta: timer shortened to 1000 ms
nua_session.c:4137 signal_call_state_change() nua(0x7f53700147d0): call
state changed: init -> calling, sent offer
soa.c:1270 soa_get_local_sdp() soa_get_local_sdp(static::0x7f533401eed0,
[0x7f537d813b38], [0x7f537d813b40], [(nil)]) called
nua_stack.c:269 nua_stack_event() nua(0x7f53700147d0): event i_state INVITE
sent
nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
tport.c:2749 tport_wakeup_pri() tport_wakeup_pri(0x7f5334004450): events IN
tport.c:2864 tport_recv_event() tport_recv_event(0x7f5334004450)
tport.c:3205 tport_recv_iovec() tport_recv_iovec(0x7f5334004450) msg
0x7f533402d0d0 from (udp/22.22.22.22:5080) has 325 bytes, veclen = 1
recv 325 bytes from udp/[33.33.33.33]:5060 at 23:41:30.883443:
   ------------------------------------------------------------------------
   SIP/2.0 100 Trying
   Via: SIP/2.0/UDP 22.22.22.22:5080
;rport=5080;received=22.22.22.22;branch=z9hG4bKX94Z74KNte97S
   From: "+111111111111" <sip:+111111111111 at 22.22.22.22>;tag=vg4Z33QvjBg5c
   To: <sip:002222222222 at 33.33.33.33>
   Call-ID: eedb70c7-63bf-1232-47b6-02082072a337
   CSeq: 60453781 INVITE
   Content-Length: 0

   ------------------------------------------------------------------------
tport.c:3023 tport_deliver() tport_deliver(0x7f5334004450): msg
0x7f533402d0d0 (325 bytes) from udp/33.33.33.33:5080/sip next=(nil)
nta.c:3297 agent_recv_response() nta: received 100 Trying for INVITE
(60453781)
nta.c:3364 agent_recv_response() nta: 100 Trying is going to a transaction
nta.c:9562 outgoing_estimate_delay() nta_outgoing: RTT is 18.27 ms
tport.c:4222 tport_release() tport_release(0x7f5334004450): 0x7f5334029a40
by 0x7f533402abd0 with 0x7f533402d0d0 (preliminary)
nta.c:1289 agent_timer() nta: timer not set
tport.c:2749 tport_wakeup_pri() tport_wakeup_pri(0x7f5334004450): events IN
tport.c:2864 tport_recv_event() tport_recv_event(0x7f5334004450)
tport.c:3205 tport_recv_iovec() tport_recv_iovec(0x7f5334004450) msg
0x7f533402c260 from (udp/22.22.22.22:5080) has 827 bytes, veclen = 1
recv 827 bytes from udp/[33.33.33.33]:5060 at 23:41:31.808643:
   ------------------------------------------------------------------------
   SIP/2.0 183 Session Progress
   Via: SIP/2.0/UDP 22.22.22.22:5080
;rport=5080;received=22.22.22.22;branch=z9hG4bKX94Z74KNte97S
   To: <sip:002222222222 at 33.33.33.33>;tag=3610568491-801417
   From: "+111111111111" <sip:+111111111111 at 22.22.22.22>;tag=vg4Z33QvjBg5c
   Call-ID: eedb70c7-63bf-1232-47b6-02082072a337
   CSeq: 60453781 INVITE
   Allow: UPDATE,PRACK,INFO,OPTIONS,BYE,INVITE,ACK,CANCEL
   Contact: <sip:002222222222 at 33.33.33.33:5060>
   Content-Type: application/sdp
   Accept: application/sdp
   Content-Length: 306

   v=0
   o=sbc-uk-bm-th02b 372172800 1401579692 IN IP4 33.33.33.33
   s=sip call
   c=IN IP4 33.33.33.34
   t=0 0
   m=audio 43554 RTP/AVP 8 101
   a=sendrecv
   a=rtpmap:8 PCMA/8000
   a=rtpmap:101 telephone-event/8000
   a=fmtp:101 0-15
   a=silenceSupp:off
   a=ptime:20
   a=maxptime:30
   a=sqn: 0
   a=cdsc:1 image udptl t38
   ------------------------------------------------------------------------
tport.c:3023 tport_deliver() tport_deliver(0x7f5334004450): msg
0x7f533402c260 (827 bytes) from udp/33.33.33.33:5080/sip next=(nil)
nta.c:3297 agent_recv_response() nta: received 183 Session Progress for
INVITE (60453781)
nta.c:3364 agent_recv_response() nta: 183 Session Progress is going to a
transaction
tport.c:4222 tport_release() tport_release(0x7f5334004450): 0x7f5334029a40
by 0x7f533402abd0 with 0x7f533402c260 (preliminary)
soa.c:1171 soa_set_remote_sdp() soa_set_remote_sdp(static::0x7f533401eed0,
(nil), 0x7f533402ca59, 306) called
soa.c:1595 soa_process_answer() soa_process_answer(static::0x7f533401eed0)
called
soa_static.c:1137 offer_answer_step()
soa_static_offer_answer_action(0x7f533401eed0, soa_process_answer): called
soa_static.c:1020 soa_sdp_mode_set() soa_sdp_mode_set(0x7f533402ade0,
0x7f5334030a90, ""): called
soa_static.c:1283 offer_answer_step() soa_static(0x7f533401eed0,
soa_process_answer): upgrade codecs with remote description
soa.c:1730 soa_activate() soa_activate(static::0x7f533401eed0, (nil)) called
nua_session.c:988 nua_session_client_response() nua(0x7f53700147d0):
INVITE: processed SDP answer in 183 Session Progress
nua_stack.c:271 nua_stack_event() nua(0x7f53700147d0): event r_invite 183
Session Progress
nua_session.c:4137 signal_call_state_change() nua(0x7f53700147d0): call
state changed: calling -> proceeding, received answer
soa.c:1098 soa_get_remote_sdp() soa_get_remote_sdp(static::0x7f533401eed0,
[0x7f537d8132d8], [0x7f537d8132e0], [(nil)]) called
soa.c:616 soa_get_params() soa_get_params(static::0x7f533401eed0, ...)
called
nua_stack.c:271 nua_stack_event() nua(0x7f53700147d0): event i_state 183
Session Progress
nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:879 nua_respond() nua: nua_respond: entering
nua_stack.c:529 nua_signal() nua(0x1d39800): sent signal r_respond
nua_stack.c:573 nua_stack_signal() nua(0x1d39800): recv signal r_respond
183 Session Progress
nua_params.c:480 nua_stack_set_params() nua: nua_stack_set_params: entering
soa.c:403 soa_set_params() soa_set_params(static::0x1d17170, ...) called
soa.c:1052 soa_set_user_sdp() soa_set_user_sdp(static::0x1d17170, (nil),
0x7f5374009b02, -1) called
soa.c:890 soa_set_capability_sdp()
soa_set_capability_sdp(static::0x1d17170, (nil), 0x7f5374009b02, -1) called
nua_session.c:2318 nua_invite_server_respond() nua:
nua_invite_server_respond: entering
soa.c:1515 soa_generate_answer() soa_generate_answer(static::0x1d17170)
called
soa_static.c:1137 offer_answer_step()
soa_static_offer_answer_action(0x1d17170, soa_generate_answer): called
soa_static.c:1168 offer_answer_step() soa_static(0x1d17170,
soa_generate_answer): generating local description
soa_static.c:1209 offer_answer_step() soa_static(0x1d17170,
soa_generate_answer): upgrade with remote description
soa_static.c:1020 soa_sdp_mode_set() soa_sdp_mode_set(0x7f5303ffea40,
0x1ca2550, ""): called
soa_static.c:1425 offer_answer_step() soa_static(0x1d17170,
soa_generate_answer): storing local description
soa.c:1730 soa_activate() soa_activate(static::0x1d17170, (nil)) called
soa.c:1270 soa_get_local_sdp() soa_get_local_sdp(static::0x1d17170,
[(nil)], [0x7f5303ffebb0], [0x7f5303ffebbc]) called
tport.c:3257 tport_tsend() tport_tsend(0x1d12180) tpn = UDP/11.11.11.11:5080
tport.c:4046 tport_resolve() tport_resolve addrinfo = 11.11.11.11:5080
tport.c:4680 tport_by_addrinfo() tport_by_addrinfo(0x1d12180): not found by
name UDP/11.11.11.11:5080
tport.c:3594 tport_vsend() tport_vsend(0x1d12180): 1137 bytes of 1137 to
udp/11.11.11.11:5080
tport.c:3492 tport_send_msg() tport_vsend returned 1137
send 1137 bytes to udp/[11.11.11.11]:5080 at 23:41:31.832193:
   ------------------------------------------------------------------------
   SIP/2.0 183 Session Progress
   Via: SIP/2.0/UDP 11.11.11.11:5080;rport=5080;branch=z9hG4bKNpj7NtK5yQjyQ
   From: "+111111111111" <sip:+111111111111 at 11.11.11.11>;tag=H53g29BtQBSKj
   To: <sip:2222222222 at 22.22.22.22:5070>;tag=rZg31jSXgjK5r
   Call-ID: eeda303b-63bf-1232-feb0-f2eff32e29d0
   CSeq: 60453781 INVITE
   Contact: <sip:2222222222 at 22.22.22.22:5070;transport=udp>
   User-Agent: FS
   Accept: application/sdp
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, NOTIFY
   Supported: timer, path, replaces
   Allow-Events: talk, hold, conference, refer
   Content-Type: application/sdp
   Content-Disposition: session
   Content-Length: 249
   X-FS-Display-Name: Outbound Call
   X-FS-Display-Number: sip:002222222222 at 22.22.22.22
   X-FS-Support: update_display,send_info
   Remote-Party-ID: "Outbound Call" <sip:002222222222 at 22.22.22.22
>;party=calling;privacy=off;screen=no

   v=0
   o=FreeSWITCH 1401550453 1401550454 IN IP4 22.22.22.22
   s=FreeSWITCH
   c=IN IP4 22.22.22.22
   t=0 0
   m=audio 29238 RTP/AVP 8 101
   a=rtpmap:8 PCMA/8000
   a=rtpmap:101 telephone-event/8000
   a=fmtp:101 0-16
   a=silenceSupp:off - - - -
   a=ptime:20
   ------------------------------------------------------------------------
nta.c:6789 incoming_reply() nta: sent 183 Session Progress for INVITE
(60453781)
nta.c:1350 set_timeout() nta: timer set to 60000 ms
nua_session.c:4137 signal_call_state_change() nua(0x1d39800): call state
changed: received -> early, sent answer
soa.c:1270 soa_get_local_sdp() soa_get_local_sdp(static::0x1d17170,
[0x7f5303ffeca8], [0x7f5303ffecb0], [(nil)]) called
soa.c:616 soa_get_params() soa_get_params(static::0x1d17170, ...) called
nua_stack.c:271 nua_stack_event() nua(0x1d39800): event i_state 183 Session
Progress
nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
nta.c:1289 agent_timer() nta: timer not set
tport.c:2749 tport_wakeup_pri() tport_wakeup_pri(0x7f5334004450): events IN
tport.c:2864 tport_recv_event() tport_recv_event(0x7f5334004450)
tport.c:3205 tport_recv_iovec() tport_recv_iovec(0x7f5334004450) msg
0x7f533402d180 from (udp/22.22.22.22:5080) has 818 bytes, veclen = 1
recv 818 bytes from udp/[33.33.33.33]:5060 at 23:41:32.714486:
   ------------------------------------------------------------------------
   SIP/2.0 180 Ringing
   Via: SIP/2.0/UDP 22.22.22.22:5080
;rport=5080;received=22.22.22.22;branch=z9hG4bKX94Z74KNte97S
   To: <sip:002222222222 at 33.33.33.33>;tag=3610568491-801417
   From: "+111111111111" <sip:+111111111111 at 22.22.22.22>;tag=vg4Z33QvjBg5c
   Call-ID: eedb70c7-63bf-1232-47b6-02082072a337
   CSeq: 60453781 INVITE
   Allow: UPDATE,PRACK,INFO,OPTIONS,BYE,INVITE,ACK,CANCEL
   Contact: <sip:002222222222 at 33.33.33.33:5060>
   Content-Type: application/sdp
   Accept: application/sdp
   Content-Length: 306

   v=0
   o=sbc-uk-bm-th02b 372172800 1401579692 IN IP4 33.33.33.33
   s=sip call
   c=IN IP4 33.33.33.34
   t=0 0
   m=audio 43554 RTP/AVP 8 101
   a=sendrecv
   a=rtpmap:8 PCMA/8000
   a=rtpmap:101 telephone-event/8000
   a=fmtp:101 0-15
   a=silenceSupp:off
   a=ptime:20
   a=maxptime:30
   a=sqn: 0
   a=cdsc:1 image udptl t38
   ------------------------------------------------------------------------
tport.c:3023 tport_deliver() tport_deliver(0x7f5334004450): msg
0x7f533402d180 (818 bytes) from udp/33.33.33.33:5080/sip next=(nil)
nta.c:3297 agent_recv_response() nta: received 180 Ringing for INVITE
(60453781)
nta.c:3364 agent_recv_response() nta: 180 Ringing is going to a transaction
tport.c:4222 tport_release() tport_release(0x7f5334004450): 0x7f5334029a40
by 0x7f533402abd0 with 0x7f533402d180 (preliminary)
soa.c:1171 soa_set_remote_sdp() soa_set_remote_sdp(static::0x7f533401eed0,
(nil), 0x7f533402e600, 306) called
soa.c:1595 soa_process_answer() soa_process_answer(static::0x7f533401eed0)
called
nua_session.c:978 nua_session_client_response() nua(0x7f53700147d0):
INVITE: error processing SDP answer in 180 Ringing
nua_stack.c:271 nua_stack_event() nua(0x7f53700147d0): event r_invite 180
Ringing
nua_session.c:4137 signal_call_state_change() nua(0x7f53700147d0): call
state changed: proceeding -> proceeding, received answer
soa.c:1098 soa_get_remote_sdp() soa_get_remote_sdp(static::0x7f533401eed0,
[0x7f537d813418], [0x7f537d813420], [(nil)]) called
soa.c:616 soa_get_params() soa_get_params(static::0x7f533401eed0, ...)
called
nua_stack.c:271 nua_stack_event() nua(0x7f53700147d0): event i_state 180
Ringing
nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
tport.c:2749 tport_wakeup_pri() tport_wakeup_pri(0x7f5334004450): events IN
tport.c:2864 tport_recv_event() tport_recv_event(0x7f5334004450)
tport.c:3205 tport_recv_iovec() tport_recv_iovec(0x7f5334004450) msg
0x7f533402f8d0 from (udp/22.22.22.22:5080) has 844 bytes, veclen = 1
recv 844 bytes from udp/[33.33.33.33]:5060 at 23:41:33.964863:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Session-Expires: 3600;refresher=uas
   Require: timer
   Via: SIP/2.0/UDP 22.22.22.22:5080
;rport=5080;received=22.22.22.22;branch=z9hG4bKX94Z74KNte97S
   To: <sip:002222222222 at 33.33.33.33>;tag=3610568491-801417
   From: "+111111111111" <sip:+111111111111 at 22.22.22.22>;tag=vg4Z33QvjBg5c
   Call-ID: eedb70c7-63bf-1232-47b6-02082072a337
   CSeq: 60453781 INVITE
   Allow: UPDATE,PRACK,INFO,OPTIONS,BYE,INVITE,ACK,CANCEL
   Contact: <sip:002222222222 at 33.33.33.33:5060>
   Content-Type: application/sdp
   Accept: application/sdp
   Content-Length: 284

   v=0
   o=sbc-uk-bm-th02b 372172800 1401579693 IN IP4 33.33.33.33
   s=sip call
   c=IN IP4 33.33.33.34
   t=0 0
   m=audio 43554 RTP/AVP 8 101
   a=rtpmap:8 PCMA/8000
   a=rtpmap:101 telephone-event/8000
   a=fmtp:101 0-15
   a=silenceSupp:off
   a=ptime:20
   a=maxptime:30
   a=cdsc:1 image udptl t38
   ------------------------------------------------------------------------
tport.c:3023 tport_deliver() tport_deliver(0x7f5334004450): msg
0x7f533402f8d0 (844 bytes) from udp/33.33.33.33:5080/sip next=(nil)
nta.c:3297 agent_recv_response() nta: received 200 OK for INVITE (60453781)
nta.c:3364 agent_recv_response() nta: 200 OK is going to a transaction
tport.c:4222 tport_release() tport_release(0x7f5334004450): 0x7f5334029a40
by 0x7f533402abd0 with 0x7f533402f8d0
nta.c:1350 set_timeout() nta: timer set to 32000 ms
nua_dialog.c:564 nua_dialog_usage_set_refresh_range() nua(): refresh
session after 1805 seconds (in [1795..1805])
soa.c:1171 soa_set_remote_sdp() soa_set_remote_sdp(static::0x7f533401eed0,
(nil), 0x7f533402eab0, 284) called
soa.c:1595 soa_process_answer() soa_process_answer(static::0x7f533401eed0)
called
nua_session.c:978 nua_session_client_response() nua(0x7f53700147d0):
INVITE: error processing SDP answer in 200 OK
nua_stack.c:271 nua_stack_event() nua(0x7f53700147d0): event r_invite 200 OK
nua_session.c:4137 signal_call_state_change() nua(0x7f53700147d0): call
state changed: proceeding -> completing, received answer
soa.c:1098 soa_get_remote_sdp() soa_get_remote_sdp(static::0x7f533401eed0,
[0x7f537d813418], [0x7f537d813420], [(nil)]) called
soa.c:616 soa_get_params() soa_get_params(static::0x7f533401eed0, ...)
called
nua_stack.c:271 nua_stack_event() nua(0x7f53700147d0): event i_state 200 OK
nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:639 nua_ack() nua: nua_ack: entering
nua_stack.c:569 nua_stack_signal() nua(0x7f53700147d0): recv signal r_ack
nua_params.c:480 nua_stack_set_params() nua: nua_stack_set_params: entering
soa.c:403 soa_set_params() soa_set_params(static::0x7f533401eed0, ...)
called
soa.c:1730 soa_activate() soa_activate(static::0x7f533401eed0, (nil)) called
nta.c:2665 nta_tpn_by_url() nta: selecting scheme sip
nua_stack.c:529 nua_signal() nua(0x7f53700147d0): sent signal r_ack
tport.c:3257 tport_tsend() tport_tsend(0x7f5334004450) tpn = UDP/
33.33.33.33:5060
nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
tport.c:4046 tport_resolve() tport_resolve addrinfo = 33.33.33.33:5060
tport.c:4680 tport_by_addrinfo() tport_by_addrinfo(0x7f5334004450): not
found by name UDP/33.33.33.33:5060
tport.c:3594 tport_vsend() tport_vsend(0x7f5334004450): 410 bytes of 410 to
udp/33.33.33.33:5060
tport.c:3492 tport_send_msg() tport_vsend returned 410
send 410 bytes to udp/[33.33.33.33]:5060 at 23:41:33.965993:
   ------------------------------------------------------------------------
   ACK sip:002222222222 at 33.33.33.33:5060 SIP/2.0
   Via: SIP/2.0/UDP 22.22.22.22:5080;rport;branch=z9hG4bKyjyr9Z4rQQZtN
   Max-Forwards: 70
   From: "+111111111111" <sip:+111111111111 at 22.22.22.22>;tag=vg4Z33QvjBg5c
   To: <sip:002222222222 at 33.33.33.33>;tag=3610568491-801417
   Call-ID: eedb70c7-63bf-1232-47b6-02082072a337
   CSeq: 60453781 ACK
   Contact: <sip:mod_sofia at 22.22.22.22:5080>
   Content-Length: 0

   ------------------------------------------------------------------------
nta.c:8302 outgoing_send() nta: sent ACK (60453781) to UDP/33.33.33.33:5060
nua_session.c:4137 signal_call_state_change() nua(0x7f53700147d0): call
state changed: completing -> ready
nua_stack.c:271 nua_stack_event() nua(0x7f53700147d0): event i_state 200
ACK sent
nua_stack.c:271 nua_stack_event() nua(0x7f53700147d0): event i_active 200
Call active
nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:879 nua_respond() nua: nua_respond: entering
nua_stack.c:529 nua_signal() nua(0x1d39800): sent signal r_respond
nua_stack.c:573 nua_stack_signal() nua(0x1d39800): recv signal r_respond
200 OK
nua_params.c:480 nua_stack_set_params() nua: nua_stack_set_params: entering
soa.c:403 soa_set_params() soa_set_params(static::0x1d17170, ...) called
soa.c:1052 soa_set_user_sdp() soa_set_user_sdp(static::0x1d17170, (nil),
0x7f53740265e4, -1) called
nua_session.c:2318 nua_invite_server_respond() nua:
nua_invite_server_respond: entering
soa.c:1270 soa_get_local_sdp() soa_get_local_sdp(static::0x1d17170,
[(nil)], [0x7f5303ffebb0], [0x7f5303ffebbc]) called
tport.c:3257 tport_tsend() tport_tsend(0x1d12180) tpn = UDP/11.11.11.11:5080
tport.c:4046 tport_resolve() tport_resolve addrinfo = 11.11.11.11:5080
tport.c:4680 tport_by_addrinfo() tport_by_addrinfo(0x1d12180): not found by
name UDP/11.11.11.11:5080
tport.c:3594 tport_vsend() tport_vsend(0x1d12180): 1098 bytes of 1098 to
udp/11.11.11.11:5080
tport.c:3492 tport_send_msg() tport_vsend returned 1098
send 1098 bytes to udp/[11.11.11.11]:5080 at 23:41:33.970944:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 11.11.11.11:5080;rport=5080;branch=z9hG4bKNpj7NtK5yQjyQ
   From: "+111111111111" <sip:+111111111111 at 11.11.11.11>;tag=H53g29BtQBSKj
   To: <sip:2222222222 at 22.22.22.22:5070>;tag=rZg31jSXgjK5r
   Call-ID: eeda303b-63bf-1232-feb0-f2eff32e29d0
   CSeq: 60453781 INVITE
   Contact: <sip:2222222222 at 22.22.22.22:5070;transport=udp>
   User-Agent: FS
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, NOTIFY
   Supported: timer, path, replaces
   Allow-Events: talk, hold, conference, refer
   Content-Type: application/sdp
   Content-Disposition: session
   Content-Length: 249
   X-FS-Display-Name: Outbound Call
   X-FS-Display-Number: sip:002222222222 at 22.22.22.22
   X-FS-Support: update_display,send_info
   Remote-Party-ID: "Outbound Call" <sip:002222222222 at 22.22.22.22
>;party=calling;privacy=off;screen=no

   v=0
   o=FreeSWITCH 1401550453 1401550454 IN IP4 22.22.22.22
   s=FreeSWITCH
   c=IN IP4 22.22.22.22
   t=0 0
   m=audio 29238 RTP/AVP 8 101
   a=rtpmap:8 PCMA/8000
   a=rtpmap:101 telephone-event/8000
   a=fmtp:101 0-16
   a=silenceSupp:off - - - -
   a=ptime:20
   ------------------------------------------------------------------------
nta.c:6789 incoming_reply() nta: sent 200 OK for INVITE (60453781)
nta.c:1348 set_timeout() nta: timer shortened to 500 ms
nua_session.c:4137 signal_call_state_change() nua(0x1d39800): call state
changed: early -> completed, sent answer
soa.c:1270 soa_get_local_sdp() soa_get_local_sdp(static::0x1d17170,
[0x7f5303ffeca8], [0x7f5303ffecb0], [(nil)]) called
soa.c:616 soa_get_params() soa_get_params(static::0x1d17170, ...) called
nua_stack.c:271 nua_stack_event() nua(0x1d39800): event i_state 200 OK
nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
tport.c:2749 tport_wakeup_pri() tport_wakeup_pri(0x1d12180): events IN
tport.c:2864 tport_recv_event() tport_recv_event(0x1d12180)
tport.c:3205 tport_recv_iovec() tport_recv_iovec(0x1d12180) msg 0x1d687a0
from (udp/22.22.22.22:5070) has 421 bytes, veclen = 1
recv 421 bytes from udp/[11.11.11.11]:5080 at 23:41:33.973154:
   ------------------------------------------------------------------------
   ACK sip:2222222222 at 22.22.22.22:5070;transport=udp SIP/2.0
   Via: SIP/2.0/UDP 11.11.11.11:5080;rport;branch=z9hG4bKQ84rSgNcS9y3e
   Max-Forwards: 70
   From: "+111111111111" <sip:+111111111111 at 11.11.11.11>;tag=H53g29BtQBSKj
   To: <sip:2222222222 at 22.22.22.22:5070>;tag=rZg31jSXgjK5r
   Call-ID: eeda303b-63bf-1232-feb0-f2eff32e29d0
   CSeq: 60453781 ACK
   Contact: <sip:mod_sofia at 11.11.11.11:5080>
   Content-Length: 0

   ------------------------------------------------------------------------
tport.c:3023 tport_deliver() tport_deliver(0x1d12180): msg 0x1d687a0 (421
bytes) from udp/11.11.11.11:5070/sip next=(nil)
nta.c:2880 agent_recv_request() nta: received ACK
sip:2222222222 at 22.22.22.22:5070;transport=udp SIP/2.0 (CSeq 60453781)
nta.c:3019 agent_recv_request() nta: ACK (60453781) is going to INVITE
(60453781)
nua_session.c:2567 process_ack_or_cancel() nua: process_ack_or_cancel:
entering
soa.c:1214 soa_clear_remote_sdp() soa_clear_remote_sdp(static::0x1d17170)
called
nua_stack.c:271 nua_stack_event() nua(0x1d39800): event i_ack 200 OK
nua_session.c:4137 signal_call_state_change() nua(0x1d39800): call state
changed: completed -> ready
nua_stack.c:271 nua_stack_event() nua(0x1d39800): event i_state 200 OK
nua_stack.c:271 nua_stack_event() nua(0x1d39800): event i_active 200 Call
active
nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:798 nua_info() nua: nua_info: entering
nua_stack.c:529 nua_signal() nua(0x1d39800): sent signal r_info
nua_stack.c:569 nua_stack_signal() nua(0x1d39800): recv signal r_info
nua_params.c:480 nua_stack_set_params() nua: nua_stack_set_params: entering
soa.c:403 soa_set_params() soa_set_params(static::0x1d17170, ...) called
nta.c:2665 nta_tpn_by_url() nta: selecting scheme sip
tport.c:3257 tport_tsend() tport_tsend(0x1d12180) tpn = */11.11.11.11:5080
tport.c:4046 tport_resolve() tport_resolve addrinfo = 11.11.11.11:5080
tport.c:4680 tport_by_addrinfo() tport_by_addrinfo(0x1d12180): not found by
name */11.11.11.11:5080
tport.c:3594 tport_vsend() tport_vsend(0x1d12180): 658 bytes of 658 to udp/
11.11.11.11:5080
tport.c:3492 tport_send_msg() tport_vsend returned 658
send 658 bytes to udp/[11.11.11.11]:5080 at 23:41:34.010625:
   ------------------------------------------------------------------------
   INFO sip:mod_sofia at 11.11.11.11:5080 SIP/2.0
   Via: SIP/2.0/UDP 22.22.22.22:5070;rport;branch=z9hG4bKDvBgeZXe0rvNg
   Max-Forwards: 70
   From: <sip:2222222222 at 22.22.22.22:5070>;tag=rZg31jSXgjK5r
   To: "+111111111111" <sip:+111111111111 at 11.11.11.11>;tag=H53g29BtQBSKj
   Call-ID: eeda303b-63bf-1232-feb0-f2eff32e29d0
   CSeq: 60453783 INFO
   Contact: <sip:2222222222 at 22.22.22.22:5070;transport=udp>
   User-Agent: FS
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, NOTIFY
   Supported: timer, path, replaces
   Content-Type: message/update_display
   Content-Length: 0
   X-FS-Display-Name: Outbound Call
   X-FS-Display-Number: 002222222222

   ------------------------------------------------------------------------
nta.c:8302 outgoing_send() nta: sent INFO (60453783) to */11.11.11.11:5080
tport.c:4160 tport_pend() tport_pend(0x1d12180): pending 0x1d14e20 for udp/
22.22.22.22:5070 (already 0)
tport.c:2749 tport_wakeup_pri() tport_wakeup_pri(0x1d12180): events IN
tport.c:2864 tport_recv_event() tport_recv_event(0x1d12180)
tport.c:3205 tport_recv_iovec() tport_recv_iovec(0x1d12180) msg 0x1d25520
from (udp/22.22.22.22:5070) has 460 bytes, veclen = 1
recv 460 bytes from udp/[11.11.11.11]:5080 at 23:41:34.012696:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 22.22.22.22:5070;rport=5070;branch=z9hG4bKDvBgeZXe0rvNg
   From: <sip:2222222222 at 22.22.22.22:5070>;tag=rZg31jSXgjK5r
   To: "+111111111111" <sip:+111111111111 at 11.11.11.11>;tag=H53g29BtQBSKj
   Call-ID: eeda303b-63bf-1232-feb0-f2eff32e29d0
   CSeq: 60453783 INFO
   User-Agent: FS
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE,
REGISTER, REFER, NOTIFY
   Supported: timer, path, replaces
   Content-Length: 0

   ------------------------------------------------------------------------
tport.c:3023 tport_deliver() tport_deliver(0x1d12180): msg 0x1d25520 (460
bytes) from udp/11.11.11.11:5070/sip next=(nil)
nta.c:3297 agent_recv_response() nta: received 200 OK for INFO (60453783)
nta.c:3364 agent_recv_response() nta: 200 OK is going to a transaction
nta.c:9562 outgoing_estimate_delay() nta_outgoing: RTT is 2.163 ms
tport.c:4222 tport_release() tport_release(0x1d12180): 0x1d14e20 by
0x1cbd870 with 0x1d25520
nua_stack.c:271 nua_stack_event() nua(0x1d39800): event r_info 200 OK
nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
nta.c:1296 agent_timer() nta: timer set next to 4502 ms
nta.c:7132 _nta_incoming_timer() nta: timer I fired, terminate 200 response
nta.c:5823 incoming_reclaim_queued() incoming_reclaim_all((nil), (nil),
0x7f5303ffeb50)
nta.c:7186 _nta_incoming_timer() nta_incoming_timer: 0/0 resent, 0/0 tout,
1/1 term, 1/1 free
nta.c:1296 agent_timer() nta: timer set next to 40 ms
nta.c:9099 outgoing_timer_dk() nta: timer K fired, terminate INFO (60453783)
nta.c:8797 outgoing_reclaim_queued() outgoing_reclaim_all((nil), (nil),
0x7f5303ffecd0)
nta.c:8927 _nta_outgoing_timer() nta_outgoing_timer: 0/0 resent, 0/0 tout,
1/1 term, 1/1 free
nta.c:1289 agent_timer() nta: timer not set
tport.c:2749 tport_wakeup_pri() tport_wakeup_pri(0x7f5334004450): events IN
tport.c:2864 tport_recv_event() tport_recv_event(0x7f5334004450)
tport.c:3205 tport_recv_iovec() tport_recv_iovec(0x7f5334004450) msg
0x7f533402ecc0 from (udp/22.22.22.22:5080) has 965 bytes, veclen = 1
recv 965 bytes from udp/[33.33.33.33]:5060 at 23:41:40.133750:
   ------------------------------------------------------------------------
   INVITE sip:mod_sofia at 22.22.22.22:5080 SIP/2.0
   Max-Forwards: 29
   Session-Expires: 3600;refresher=uac
   Min-SE: 600
   Supported: timer, 100rel
   To: "+111111111111" <sip:+111111111111 at 22.22.22.22>;tag=vg4Z33QvjBg5c
   From: <sip:002222222222 at 33.33.33.33>;tag=3610568491-801417
   Call-ID: eedb70c7-63bf-1232-47b6-02082072a337
   CSeq: 2 INVITE
   Allow: UPDATE,PRACK,INFO,OPTIONS,BYE,INVITE,ACK,CANCEL
   Via: SIP/2.0/UDP 33.33.33.33:5060
;branch=z9hG4bKeff490529a8e1aac116cef0e2deb9648
   Contact: <sip:002222222222 at 33.33.33.33:5060>
   Content-Type: application/sdp
   Accept: application/sdp
   Content-Length: 353

   v=0
   o=sbc-uk-bm-th02b 372172800 1401579694 IN IP4 33.33.33.33
   s=sip call
   c=IN IP4 33.33.33.34
   t=0 0
   m=image 43554 udptl t38
   a=sendrecv
   a=T38FaxVersion:0
   a=T38MaxBitRate:14400
   a=T38FaxRateManagement:transferredTCF
   a=T38FaxMaxBuffer:300
   a=T38FaxMaxDatagram:176
   a=T38FaxUdpEC:t38UDPRedundancy
   a=silenceSupp:off
   a=ptime:20
   a=maxptime:30
   ------------------------------------------------------------------------
tport.c:3023 tport_deliver() tport_deliver(0x7f5334004450): msg
0x7f533402ecc0 (965 bytes) from udp/33.33.33.33:5080/sip next=(nil)
nta.c:2880 agent_recv_request() nta: received INVITE
sip:mod_sofia at 22.22.22.22:5080 SIP/2.0 (CSeq 2)
nta.c:3246 agent_aliases() nta: canonizing sip:mod_sofia at 22.22.22.22:5080
with contact
nta.c:3060 agent_recv_request() nta: INVITE (2) going to existing leg
nta.c:1348 set_timeout() nta: timer shortened to 200 ms
nua_server.c:102 nua_stack_process_request() nua:
nua_stack_process_request: entering
soa.c:1302 soa_init_offer_answer()
soa_init_offer_answer(static::0x7f533401eed0) called
soa.c:1171 soa_set_remote_sdp() soa_set_remote_sdp(static::0x7f533401eed0,
(nil), 0x7f533402f514, 353) called
tport.c:3257 tport_tsend() tport_tsend(0x7f5334004450) tpn = UDP/
33.33.33.33:5060
tport.c:4046 tport_resolve() tport_resolve addrinfo = 33.33.33.33:5060
tport.c:4680 tport_by_addrinfo() tport_by_addrinfo(0x7f5334004450): not
found by name UDP/33.33.33.33:5060
tport.c:3594 tport_vsend() tport_vsend(0x7f5334004450): 344 bytes of 344 to
udp/33.33.33.33:5060
tport.c:3492 tport_send_msg() tport_vsend returned 344
send 344 bytes to udp/[33.33.33.33]:5060 at 23:41:40.134062:
   ------------------------------------------------------------------------
   SIP/2.0 100 Trying
   Via: SIP/2.0/UDP 33.33.33.33:5060
;branch=z9hG4bKeff490529a8e1aac116cef0e2deb9648
   From: <sip:002222222222 at 33.33.33.33>;tag=3610568491-801417
   To: "+111111111111" <sip:+111111111111 at 22.22.22.22>;tag=vg4Z33QvjBg5c
   Call-ID: eedb70c7-63bf-1232-47b6-02082072a337
   CSeq: 2 INVITE
   User-Agent: FS
   Content-Length: 0

   ------------------------------------------------------------------------
nta.c:6789 incoming_reply() nta: sent 100 Trying for INVITE (2)
nua_stack.c:271 nua_stack_event() nua(0x7f53700147d0): event i_invite 100
Trying
nua_session.c:4143 signal_call_state_change() nua(0x7f53700147d0): ready
call updated: received received offer
soa.c:1098 soa_get_remote_sdp() soa_get_remote_sdp(static::0x7f533401eed0,
[0x7f537d813598], [0x7f537d8135a0], [(nil)]) called
nua_stack.c:271 nua_stack_event() nua(0x7f53700147d0): event i_state 100
Trying
nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
nta.c:1296 agent_timer() nta: timer set next to 25631 ms
nta.c:9099 outgoing_timer_dk() nta: timer D fired, terminate INVITE
(60453781)
nta.c:8797 outgoing_reclaim_queued() outgoing_reclaim_all((nil), (nil),
0x7f537d813cd0)
nta.c:8927 _nta_outgoing_timer() nta_outgoing_timer: 0/0 resent, 0/1 tout,
1/1 term, 1/2 free
nta.c:1296 agent_timer() nta: timer set next to 1 ms
nta.c:8980 outgoing_timer_bf() nta: timer F fired, terminating ACK
(60453781)
nta.c:8797 outgoing_reclaim_queued() outgoing_reclaim_all((nil), (nil),
0x7f537d813cd0)
nta.c:8927 _nta_outgoing_timer() nta_outgoing_timer: 0/0 resent, 1/1 tout,
0/0 term, 1/1 free
nta.c:1289 agent_timer() nta: timer not set

tport.c:2749 tport_wakeup_pri() tport_wakeup_pri(0x7f5334004450): events IN
tport.c:2864 tport_recv_event() tport_recv_event(0x7f5334004450)
tport.c:3205 tport_recv_iovec() tport_recv_iovec(0x7f5334004450) msg
0x7f5334029a40 from (udp/22.22.22.22:5080) has 512 bytes, veclen = 1
recv 512 bytes from udp/[33.33.33.33]:5060 at 23:44:22.741683:
   ------------------------------------------------------------------------
   CANCEL sip:mod_sofia at 22.22.22.22:5080 SIP/2.0
   Max-Forwards: 69
   To: "+111111111111" <sip:+111111111111 at 22.22.22.22>;tag=vg4Z33QvjBg5c
   From: <sip:002222222222 at 33.33.33.33>;tag=3610568491-801417
   Call-ID: eedb70c7-63bf-1232-47b6-02082072a337
   CSeq: 2 CANCEL
   Allow: UPDATE,INFO,OPTIONS,BYE,INVITE,ACK,CANCEL
   Via: SIP/2.0/UDP 33.33.33.33:5060
;branch=z9hG4bKeff490529a8e1aac116cef0e2deb9648
   Contact: <sip:002222222222 at 33.33.33.33:5060>
   Reason: SIP;cause=403;text="Forbidden"
   Content-Length: 0

   ------------------------------------------------------------------------
tport.c:3023 tport_deliver() tport_deliver(0x7f5334004450): msg
0x7f5334029a40 (512 bytes) from udp/33.33.33.33:5080/sip next=(nil)
nta.c:2880 agent_recv_request() nta: received CANCEL
sip:mod_sofia at 22.22.22.22:5080 SIP/2.0 (CSeq 2)
nta.c:3026 agent_recv_request() nta: CANCEL (2) is going to INVITE (2)
tport.c:3257 tport_tsend() tport_tsend(0x7f5334004450) tpn = UDP/
33.33.33.33:5060
tport.c:4046 tport_resolve() tport_resolve addrinfo = 33.33.33.33:5060
tport.c:4680 tport_by_addrinfo() tport_by_addrinfo(0x7f5334004450): not
found by name UDP/33.33.33.33:5060
tport.c:3594 tport_vsend() tport_vsend(0x7f5334004450): 321 bytes of 321 to
udp/33.33.33.33:5060
tport.c:3492 tport_send_msg() tport_vsend returned 321
send 321 bytes to udp/[33.33.33.33]:5060 at 23:44:22.741804:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 33.33.33.33:5060
;branch=z9hG4bKeff490529a8e1aac116cef0e2deb9648
   From: <sip:002222222222 at 33.33.33.33>;tag=3610568491-801417
   To: "+111111111111" <sip:+111111111111 at 22.22.22.22>;tag=vg4Z33QvjBg5c
   Call-ID: eedb70c7-63bf-1232-47b6-02082072a337
   CSeq: 2 CANCEL
   Content-Length: 0

   ------------------------------------------------------------------------
nua_session.c:2567 process_ack_or_cancel() nua: process_ack_or_cancel:
entering
nua_stack.c:271 nua_stack_event() nua(0x7f53700147d0): event i_cancel 200 OK
nua_session.c:2318 nua_invite_server_respond() nua:
nua_invite_server_respond: entering
soa.c:1214 soa_clear_remote_sdp()
soa_clear_remote_sdp(static::0x7f533401eed0) called
tport.c:3257 tport_tsend() tport_tsend(0x7f5334004450) tpn = UDP/
33.33.33.33:5060
tport.c:4046 tport_resolve() tport_resolve addrinfo = 33.33.33.33:5060
tport.c:4680 tport_by_addrinfo() tport_by_addrinfo(0x7f5334004450): not
found by name UDP/33.33.33.33:5060
nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
tport.c:3594 tport_vsend() tport_vsend(0x7f5334004450): 505 bytes of 505 to
udp/33.33.33.33:5060
tport.c:3492 tport_send_msg() tport_vsend returned 505
send 505 bytes to udp/[33.33.33.33]:5060 at 23:44:22.742006:
   ------------------------------------------------------------------------
   SIP/2.0 487 Request Terminated
   Via: SIP/2.0/UDP 33.33.33.33:5060
;branch=z9hG4bKeff490529a8e1aac116cef0e2deb9648
   From: <sip:002222222222 at 33.33.33.33>;tag=3610568491-801417
   To: "+111111111111" <sip:+111111111111 at 22.22.22.22>;tag=vg4Z33QvjBg5c
   Call-ID: eedb70c7-63bf-1232-47b6-02082072a337
   CSeq: 2 INVITE
   User-Agent: FS
   Accept: application/sdp
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE,
REGISTER, REFER, NOTIFY
   Supported: timer, path, replaces
   Content-Length: 0

   ------------------------------------------------------------------------
nta.c:6789 incoming_reply() nta: sent 487 Request Terminated for INVITE (2)
nta.c:1350 set_timeout() nta: timer set to 500 ms
nua_session.c:4143 signal_call_state_change() nua(0x7f53700147d0): ready
call updated: init
nua_stack.c:271 nua_stack_event() nua(0x7f53700147d0): event i_state 487
Request Terminated
nua_stack.c:271 nua_stack_event() nua(0x7f53700147d0): event i_active 487
Call active
soa.c:1302 soa_init_offer_answer()
soa_init_offer_answer(static::0x7f533401eed0) called
nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
tport.c:2749 tport_wakeup_pri() tport_wakeup_pri(0x7f5334004450): events IN
nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
tport.c:2864 tport_recv_event() tport_recv_event(0x7f5334004450)
nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
tport.c:3205 tport_recv_iovec() tport_recv_iovec(0x7f5334004450) msg
0x7f533402d0b0 from (udp/22.22.22.22:5080) has 518 bytes, veclen = 1
nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
recv 518 bytes from udp/[33.33.33.33]:5060 at 23:44:22.742365:
   ------------------------------------------------------------------------
nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
   BYE sip:mod_sofia at 22.22.22.22:5080 SIP/2.0
nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
   Max-Forwards: 69
   To: "+111111111111" <sip:+111111111111 at 22.22.22.22>;tag=vg4Z33QvjBg5c
   From: <sip:002222222222 at 33.33.33.33>;tag=3610568491-801417
   Call-ID: eedb70c7-63bf-1232-47b6-02082072a337
   CSeq: 3 BYE
   Allow: UPDATE,INFO,OPTIONS,BYE,INVITE,ACK,CANCEL
   Via: SIP/2.0/UDP 33.33.33.33:5060
;branch=z9hG4bK6d90a278ed6e9b8f21c26aabb75e85e5
   Contact: <sip:002222222222 at 33.33.33.33:5060>
   Reason: Q.850;cause=16;text="Normal call clearing"
   Content-Length: 0

   ------------------------------------------------------------------------
nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
tport.c:3023 tport_deliver() tport_deliver(0x7f5334004450): msg
0x7f533402d0b0 (518 bytes) from udp/33.33.33.33:5080/sip next=(nil)
nta.c:2880 agent_recv_request() nta: received BYE
sip:mod_sofia at 22.22.22.22:5080 SIP/2.0 (CSeq 3)
nta.c:3246 agent_aliases() nta: canonizing sip:mod_sofia at 22.22.22.22:5080
with contact
nta.c:3060 agent_recv_request() nta: BYE (3) going to existing leg
nua_server.c:102 nua_stack_process_request() nua:
nua_stack_process_request: entering
nua_stack.c:271 nua_stack_event() nua(0x7f53700147d0): event i_bye 100
Trying
nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:879 nua_respond() nua: nua_respond: entering
nua_stack.c:529 nua_signal() nua(0x7f53700147d0): sent signal r_respond
nua.c:342 nua_handle_bind() nua: nua_handle_bind: entering
nua.c:921 nua_handle_destroy() nua: nua_handle_destroy: entering
nua_stack.c:529 nua_signal() nua(0x7f53700147d0): sent signal r_destroy
nua_stack.c:573 nua_stack_signal() nua(0x7f53700147d0): recv signal
r_respond 200 OK
nua_params.c:480 nua_stack_set_params() nua: nua_stack_set_params: entering
soa.c:403 soa_set_params() soa_set_params(static::0x7f533401eed0, ...)
called
tport.c:3257 tport_tsend() tport_tsend(0x7f5334004450) tpn = UDP/
33.33.33.33:5060
tport.c:4046 tport_resolve() tport_resolve addrinfo = 33.33.33.33:5060
tport.c:4680 tport_by_addrinfo() tport_by_addrinfo(0x7f5334004450): not
found by name UDP/33.33.33.33:5060
tport.c:3594 tport_vsend() tport_vsend(0x7f5334004450): 461 bytes of 461 to
udp/33.33.33.33:5060
tport.c:3492 tport_send_msg() tport_vsend returned 461
send 461 bytes to udp/[33.33.33.33]:5060 at 23:44:22.744366:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 33.33.33.33:5060
;branch=z9hG4bK6d90a278ed6e9b8f21c26aabb75e85e5
   From: <sip:002222222222 at 33.33.33.33>;tag=3610568491-801417
   To: "+111111111111" <sip:+111111111111 at 22.22.22.22>;tag=vg4Z33QvjBg5c
   Call-ID: eedb70c7-63bf-1232-47b6-02082072a337
   CSeq: 3 BYE
   User-Agent: FS
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE,
REGISTER, REFER, NOTIFY
   Supported: timer, path, replaces
   Content-Length: 0

   ------------------------------------------------------------------------
nta.c:6789 incoming_reply() nta: sent 200 OK for BYE (3)
nua_dialog.c:397 nua_dialog_usage_remove_at() nua(0x7f53700147d0): removing
session usage
nua_session.c:4137 signal_call_state_change() nua(0x7f53700147d0): call
state changed: ready -> terminated
nua_stack.c:271 nua_stack_event() nua(0x7f53700147d0): event i_state 200
Session Terminated
nua_stack.c:271 nua_stack_event() nua(0x7f53700147d0): event i_terminated
200 Session Terminated
soa.c:356 soa_destroy() soa_destroy(static::0x7f533401eed0) called
nta.c:4468 nta_leg_destroy() nta_leg_destroy(0x7f5334018320)
nua_stack.c:569 nua_stack_signal() nua(0x7f53700147d0): recv signal
r_destroy
nta.c:4468 nta_leg_destroy() nta_leg_destroy((nil))
nua.c:645 nua_bye() nua: nua_bye: entering
nua_stack.c:529 nua_signal() nua(0x1d39800): sent signal r_bye
nua_stack.c:569 nua_stack_signal() nua(0x1d39800): recv signal r_bye
nua_params.c:480 nua_stack_set_params() nua: nua_stack_set_params: entering
soa.c:403 soa_set_params() soa_set_params(static::0x1d17170, ...) called
soa.c:1784 soa_terminate() soa_terminate(static::0x1d17170) called
soa.c:1302 soa_init_offer_answer() soa_init_offer_answer(static::0x1d17170)
called
nta.c:2665 nta_tpn_by_url() nta: selecting scheme sip
tport.c:3257 tport_tsend() tport_tsend(0x1d12180) tpn = */11.11.11.11:5080
tport.c:4046 tport_resolve() tport_resolve addrinfo = 11.11.11.11:5080
tport.c:4680 tport_by_addrinfo() tport_by_addrinfo(0x1d12180): not found by
name */11.11.11.11:5080
tport.c:3594 tport_vsend() tport_vsend(0x1d12180): 599 bytes of 599 to udp/
11.11.11.11:5080
tport.c:3492 tport_send_msg() tport_vsend returned 599
send 599 bytes to udp/[11.11.11.11]:5080 at 23:44:22.751815:
   ------------------------------------------------------------------------
   BYE sip:mod_sofia at 11.11.11.11:5080 SIP/2.0
   Via: SIP/2.0/UDP 22.22.22.22:5070;rport;branch=z9hG4bKe548FtejX1j8B
   Max-Forwards: 70
   From: <sip:2222222222 at 22.22.22.22:5070>;tag=rZg31jSXgjK5r
   To: "+111111111111" <sip:+111111111111 at 11.11.11.11>;tag=H53g29BtQBSKj
   Call-ID: eeda303b-63bf-1232-feb0-f2eff32e29d0
   CSeq: 60453784 BYE
   Contact: <sip:2222222222 at 22.22.22.22:5070;transport=udp>
   User-Agent: FS
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, NOTIFY
   Supported: timer, path, replaces
   Reason: Q.850;cause=16;text="Normal call clearing"
   Content-Length: 0

   ------------------------------------------------------------------------
nta.c:8302 outgoing_send() nta: sent BYE (60453784) to */11.11.11.11:5080
tport.c:4160 tport_pend() tport_pend(0x1d12180): pending 0x1d14e20 for udp/
22.22.22.22:5070 (already 0)
nta.c:1350 set_timeout() nta: timer set to 32000 ms
nta.c:1348 set_timeout() nta: timer shortened to 1000 ms
tport.c:2749 tport_wakeup_pri() tport_wakeup_pri(0x1d12180): events IN
tport.c:2864 tport_recv_event() tport_recv_event(0x1d12180)
tport.c:3205 tport_recv_iovec() tport_recv_iovec(0x1d12180) msg 0x1ca2550
from (udp/22.22.22.22:5070) has 459 bytes, veclen = 1
recv 459 bytes from udp/[11.11.11.11]:5080 at 23:44:22.755074:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 22.22.22.22:5070;rport=5070;branch=z9hG4bKe548FtejX1j8B
   From: <sip:2222222222 at 22.22.22.22:5070>;tag=rZg31jSXgjK5r
   To: "+111111111111" <sip:+111111111111 at 11.11.11.11>;tag=H53g29BtQBSKj
   Call-ID: eeda303b-63bf-1232-feb0-f2eff32e29d0
   CSeq: 60453784 BYE
   User-Agent: FS
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE,
REGISTER, REFER, NOTIFY
   Supported: timer, path, replaces
   Content-Length: 0

   ------------------------------------------------------------------------
tport.c:3023 tport_deliver() tport_deliver(0x1d12180): msg 0x1ca2550 (459
bytes) from udp/11.11.11.11:5070/sip next=(nil)
nta.c:3297 agent_recv_response() nta: received 200 OK for BYE (60453784)
nta.c:3364 agent_recv_response() nta: 200 OK is going to a transaction
nta.c:9562 outgoing_estimate_delay() nta_outgoing: RTT is 3.357 ms
tport.c:4222 tport_release() tport_release(0x1d12180): 0x1d14e20 by
0x1d27170 with 0x1ca2550
nua_stack.c:271 nua_stack_event() nua(0x1d39800): event r_bye 200 OK
nua_session.c:4137 signal_call_state_change() nua(0x1d39800): call state
changed: terminating -> terminated
nua_stack.c:271 nua_stack_event() nua(0x1d39800): event i_state 200 to BYE
nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:342 nua_handle_bind() nua: nua_handle_bind: entering
nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:921 nua_handle_destroy() nua: nua_handle_destroy: entering
nua_stack.c:569 nua_stack_signal() nua(0x1d39800): recv signal r_destroy
nta.c:4468 nta_leg_destroy() nta_leg_destroy((nil))
nua_stack.c:529 nua_signal() nua(0x1d39800): sent signal r_destroy
tport.c:2749 tport_wakeup_pri() tport_wakeup_pri(0x7f5334004450): events IN
tport.c:2864 tport_recv_event() tport_recv_event(0x7f5334004450)
tport.c:3205 tport_recv_iovec() tport_recv_iovec(0x7f5334004450) msg
0x7f5334032af0 from (udp/22.22.22.22:5080) has 466 bytes, veclen = 1
recv 466 bytes from udp/[33.33.33.33]:5060 at 23:44:22.759701:
   ------------------------------------------------------------------------
   ACK sip:mod_sofia at 22.22.22.22:5080 SIP/2.0
   Max-Forwards: 70
   To: "+111111111111" <sip:+111111111111 at 22.22.22.22>;tag=vg4Z33QvjBg5c
   From: <sip:002222222222 at 33.33.33.33>;tag=3610568491-801417
   Call-ID: eedb70c7-63bf-1232-47b6-02082072a337
   CSeq: 2 ACK
   Allow: UPDATE,INFO,OPTIONS,BYE,INVITE,ACK,CANCEL
   Via: SIP/2.0/UDP 33.33.33.33:5060
;branch=z9hG4bKeff490529a8e1aac116cef0e2deb9648
   Contact: <sip:002222222222 at 33.33.33.33:5060>
   Content-Length: 0

   ------------------------------------------------------------------------
tport.c:3023 tport_deliver() tport_deliver(0x7f5334004450): msg
0x7f5334032af0 (466 bytes) from udp/33.33.33.33:5080/sip next=(nil)
nta.c:2880 agent_recv_request() nta: received ACK
sip:mod_sofia at 22.22.22.22:5080 SIP/2.0 (CSeq 2)
nta.c:3019 agent_recv_request() nta: ACK (2) is going to INVITE (2)
nta.c:1296 agent_timer() nta: timer set next to 4517 ms
nta.c:1296 agent_timer() nta: timer set next to 4002 ms
nta.c:9099 outgoing_timer_dk() nta: timer K fired, terminate BYE (60453784)
nta.c:8797 outgoing_reclaim_queued() outgoing_reclaim_all((nil), (nil),
0x7f5303ffecd0)
nta.c:8927 _nta_outgoing_timer() nta_outgoing_timer: 0/0 resent, 0/0 tout,
1/1 term, 1/1 free
nta.c:1289 agent_timer() nta: timer not set
nta.c:7132 _nta_incoming_timer() nta: timer I fired, terminate 487 response
nta.c:5823 incoming_reclaim_queued() incoming_reclaim_all((nil), (nil),
0x7f537d813b50)
nta.c:7186 _nta_incoming_timer() nta_incoming_timer: 0/0 resent, 0/0 tout,
1/2 term, 1/2 free
nta.c:1296 agent_timer() nta: timer set next to 26984 ms
nta.c:7157 _nta_incoming_timer() nta: timer J fired, terminate 200 response
nta.c:5823 incoming_reclaim_queued() incoming_reclaim_all((nil), (nil),
0x7f537d813b50)
nta.c:7186 _nta_incoming_timer() nta_incoming_timer: 0/0 resent, 0/0 tout,
1/1 term, 1/1 free
nta.c:1289 agent_timer() nta: timer not set




-- 
Yours sincerely,
Vladimir Getmanshchuk
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20140531/32aaca98/attachment-0001.html 


Join us at ClueCon 2016 Aug 8-12, 2016
More information about the FreeSWITCH-users mailing list