Your pastebin server returned: "Query failure: Got a packet bigger than 'max_allowed_packet' bytes", so log attached.
And this mail list looks like does not receive emails with large attachments..

Please advice how I can provide the trace.

Thanks!


On Mon, Jun 2, 2014 at 5:48 AM, Michael Jerris <mike@jerris.com> wrote:
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@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:
   ------------------------------------------------------------------------
   Via: SIP/2.0/UDP 11.11.11.11:5080;rport;branch=z9hG4bKNpj7NtK5yQjyQ
   Max-Forwards: 70
   From: "+111111111111" <sip:+111111111111@11.11.11.11>;tag=H53g29BtQBSKj
   Call-ID: eeda303b-63bf-1232-feb0-f2eff32e29d0
   CSeq: 60453781 INVITE
   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@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@22.22.22.22:5070 SIP/2.0 (CSeq 60453781)
nta.c:3246 agent_aliases() nta: canonizing sip:2222222222@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@11.11.11.11>;tag=H53g29BtQBSKj
   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@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@22.22.22.22>;tag=vg4Z33QvjBg5c
   Call-ID: eedb70c7-63bf-1232-47b6-02082072a337
   CSeq: 60453781 INVITE
   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@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@22.22.22.22>;tag=vg4Z33QvjBg5c
   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@33.33.33.33>;tag=3610568491-801417
   From: "+111111111111" <sip:+111111111111@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
   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@11.11.11.11>;tag=H53g29BtQBSKj
   To: <sip:2222222222@22.22.22.22:5070>;tag=rZg31jSXgjK5r
   Call-ID: eeda303b-63bf-1232-feb0-f2eff32e29d0
   CSeq: 60453781 INVITE
   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@22.22.22.22
   X-FS-Support: update_display,send_info
   Remote-Party-ID: "Outbound Call" <sip:002222222222@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@33.33.33.33>;tag=3610568491-801417
   From: "+111111111111" <sip:+111111111111@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
   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@33.33.33.33>;tag=3610568491-801417
   From: "+111111111111" <sip:+111111111111@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
   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:
   ------------------------------------------------------------------------
   Via: SIP/2.0/UDP 22.22.22.22:5080;rport;branch=z9hG4bKyjyr9Z4rQQZtN
   Max-Forwards: 70
   From: "+111111111111" <sip:+111111111111@22.22.22.22>;tag=vg4Z33QvjBg5c
   To: <sip:002222222222@33.33.33.33>;tag=3610568491-801417
   Call-ID: eedb70c7-63bf-1232-47b6-02082072a337
   CSeq: 60453781 ACK
   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@11.11.11.11>;tag=H53g29BtQBSKj
   To: <sip:2222222222@22.22.22.22:5070>;tag=rZg31jSXgjK5r
   Call-ID: eeda303b-63bf-1232-feb0-f2eff32e29d0
   CSeq: 60453781 INVITE
   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@22.22.22.22
   X-FS-Support: update_display,send_info
   Remote-Party-ID: "Outbound Call" <sip:002222222222@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:
   ------------------------------------------------------------------------
   Via: SIP/2.0/UDP 11.11.11.11:5080;rport;branch=z9hG4bKQ84rSgNcS9y3e
   Max-Forwards: 70
   From: "+111111111111" <sip:+111111111111@11.11.11.11>;tag=H53g29BtQBSKj
   To: <sip:2222222222@22.22.22.22:5070>;tag=rZg31jSXgjK5r
   Call-ID: eeda303b-63bf-1232-feb0-f2eff32e29d0
   CSeq: 60453781 ACK
   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@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:
   ------------------------------------------------------------------------
   Via: SIP/2.0/UDP 22.22.22.22:5070;rport;branch=z9hG4bKDvBgeZXe0rvNg
   Max-Forwards: 70
   From: <sip:2222222222@22.22.22.22:5070>;tag=rZg31jSXgjK5r
   To: "+111111111111" <sip:+111111111111@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, 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@22.22.22.22:5070>;tag=rZg31jSXgjK5r
   To: "+111111111111" <sip:+111111111111@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@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@22.22.22.22>;tag=vg4Z33QvjBg5c
   From: <sip:002222222222@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
   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@22.22.22.22:5080 SIP/2.0 (CSeq 2)
nta.c:3246 agent_aliases() nta: canonizing sip:mod_sofia@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@33.33.33.33>;tag=3610568491-801417
   To: "+111111111111" <sip:+111111111111@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@22.22.22.22:5080 SIP/2.0
   Max-Forwards: 69
   To: "+111111111111" <sip:+111111111111@22.22.22.22>;tag=vg4Z33QvjBg5c
   From: <sip:002222222222@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
   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@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@33.33.33.33>;tag=3610568491-801417
   To: "+111111111111" <sip:+111111111111@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@33.33.33.33>;tag=3610568491-801417
   To: "+111111111111" <sip:+111111111111@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
nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
   Max-Forwards: 69
   To: "+111111111111" <sip:+111111111111@22.22.22.22>;tag=vg4Z33QvjBg5c
   From: <sip:002222222222@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
   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@22.22.22.22:5080 SIP/2.0 (CSeq 3)
nta.c:3246 agent_aliases() nta: canonizing sip:mod_sofia@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@33.33.33.33>;tag=3610568491-801417
   To: "+111111111111" <sip:+111111111111@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:
   ------------------------------------------------------------------------
   Via: SIP/2.0/UDP 22.22.22.22:5070;rport;branch=z9hG4bKe548FtejX1j8B
   Max-Forwards: 70
   From: <sip:2222222222@22.22.22.22:5070>;tag=rZg31jSXgjK5r
   To: "+111111111111" <sip:+111111111111@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, 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@22.22.22.22:5070>;tag=rZg31jSXgjK5r
   To: "+111111111111" <sip:+111111111111@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:
   ------------------------------------------------------------------------
   Max-Forwards: 70
   To: "+111111111111" <sip:+111111111111@22.22.22.22>;tag=vg4Z33QvjBg5c
   From: <sip:002222222222@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
   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@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



_________________________________________________________________________
Professional FreeSWITCH Consulting Services:
consulting@freeswitch.org
http://www.freeswitchsolutions.com

FreeSWITCH-powered IP PBX: The CudaTel Communication Server
http://www.cudatel.com

Official FreeSWITCH Sites
http://www.freeswitch.org
http://wiki.freeswitch.org
http://www.cluecon.com

FreeSWITCH-users mailing list
FreeSWITCH-users@lists.freeswitch.org
http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
http://www.freeswitch.org




--
Yours sincerely,
Vladimir Getmanshchuk