[Freeswitch-users] T38 fax. FS fails after reINVITE
Michael Jerris
mike at jerris.com
Mon Jun 2 16:48:48 MSD 2014
Is there any freeswitch debug that goes along with this? The sofia trace is helpful, the sofia debug, not as much, and the freeswitch debug would probably be helpful as well.
On Jun 1, 2014, at 4:32 AM, Vladimir Getmanshchuk <vladget at gmail.com> wrote:
> 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
>
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20140602/297b764e/attachment-0001.html
Join us at ClueCon 2016 Aug 8-12, 2016
More information about the FreeSWITCH-users
mailing list