2018-01-08 13:19:10.279480 [NOTICE] mod_logfile.c:213 New log started. 2018-01-08 13:19:10.279480 [NOTICE] mod_cdr_csv.c:127 Rotated CDR logfile C:\Streem\LogFiles\freeswitch/cdr-csv/Master.csv 1790ebfe-b1a0-4833-9ef2-7b7d03fd6002 2018-01-08 13:19:46.699587 [NOTICE] switch_channel.c:1104 New Channel sofia/external/18156548285 [1790ebfe-b1a0-4833-9ef2-7b7d03fd6002] ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:529 nua_signal() nua(000000DB8F7B77A0): sent signal r_invite ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:569 nua_stack_signal() nua(000000DB8F7B77A0): recv signal r_invite ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:280 soa_clone() soa_clone(static::000000DB8F2E3F60, 000000DB8F5301B0, 000000DB8F7B77A0) called ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:403 soa_set_params() soa_set_params(static::000000DB8F9933D0, ...) called ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:403 soa_set_params() soa_set_params(static::000000DB8F9933D0, ...) called ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1052 soa_set_user_sdp() soa_set_user_sdp(static::000000DB8F9933D0, 0000000000000000, 000000DB9102FE57, -1) called ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:890 soa_set_capability_sdp() soa_set_capability_sdp(static::000000DB8F9933D0, 0000000000000000, 000000DB9102FE57, -1) called ..\..\sofia-sip\libsofia-sip-ua\nua\nua_dialog.c:338 nua_dialog_usage_add() nua(000000DB8F7B77A0): adding session usage ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:4417 nta_leg_tcreate() nta_leg_tcreate(000000DB8F7AED90) ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1302 soa_init_offer_answer() soa_init_offer_answer(static::000000DB8F9933D0) called ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1426 soa_generate_offer() soa_generate_offer(static::000000DB8F9933D0, 0) called ..\..\sofia-sip\libsofia-sip-ua\soa\soa_static.c:1148 offer_answer_step() soa_static_offer_answer_action(000000DB8F9933D0, soa_generate_offer): called ..\..\sofia-sip\libsofia-sip-ua\soa\soa_static.c:1189 offer_answer_step() soa_static(000000DB8F9933D0, soa_generate_offer): generating local description ..\..\sofia-sip\libsofia-sip-ua\soa\soa_static.c:1217 offer_answer_step() soa_static(000000DB8F9933D0, soa_generate_offer): upgrade with local description ..\..\sofia-sip\libsofia-sip-ua\soa\soa_static.c:1029 soa_sdp_mode_set() soa_sdp_mode_set(000000DB9064D660, 0000000000000000, ""): called ..\..\sofia-sip\libsofia-sip-ua\soa\soa_static.c:1446 offer_answer_step() soa_static(000000DB8F9933D0, soa_generate_offer): storing local description ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1270 soa_get_local_sdp() soa_get_local_sdp(static::000000DB8F9933D0, [0000000000000000], [000000DB9064F7B0], [000000DB9064F7E8]) called ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:2665 nta_tpn_by_url() nta: selecting scheme sip ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3257 tport_tsend() tport_tsend(000000DB8F2E4C10) tpn = */10.10.5.14:5060 ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:4046 tport_resolve() tport_resolve addrinfo = 10.10.5.14:5060 ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:4680 tport_by_addrinfo() tport(000000DB8F2E4C10): not found by name */10.10.5.14:5060 ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3594 tport_vsend() tport_vsend(000000DB8F2E4C10): 1145 bytes of 1145 to udp/10.10.5.14:5060 ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3492 tport_send_msg() tport_vsend returned 1145 send 1145 bytes to udp/[10.10.5.14]:5060 at 19:19:46.705499: ------------------------------------------------------------------------ INVITE sip:18156548285@10.10.5.14 SIP/2.0 Via: SIP/2.0/UDP 10.10.101.238:5080;rport;branch=z9hG4bK5c1vgeDUmZHBa Max-Forwards: 70 From: "SUPPORTSTREEMVM" ;tag=66mNXc2Z3m12B To: Call-ID: bae55b93-6f4b-1236-cf98-dd1cb2d72f2a CSeq: 117383529 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/1.6.19+git~20171109T180403Z~27aebc7253~64bit 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: 293 X-FS-Support: update_display,send_info Remote-Party-ID: "SUPPORTSTREEMVM" ;party=calling;screen=yes;privacy=off v=0 o=FreeSWITCH 1515382836 1515382837 IN IP4 10.10.101.238 s=FreeSWITCH c=IN IP4 10.10.101.238 t=0 0 m=audio 56350 RTP/AVP 0 8 3 101 13 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:3 GSM/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=rtpmap:13 CN/8000 a=ptime:20 ------------------------------------------------------------------------ ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:8304 outgoing_send() nta: sent INVITE (117383529) to */10.10.5.14:5060 ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:4160 tport_pend() tport_pend(000000DB8F2E4C10): pending 000000DB8F5A0CB0 for udp/10.10.101.238:5080 (already 0) ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:1350 set_timeout() nta: timer set to 32000 ms ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:1348 set_timeout() nta: timer shortened to 1000 ms ..\..\sofia-sip\libsofia-sip-ua\nua\nua_session.c:4139 signal_call_state_change() nua(000000DB8F7B77A0): call state changed: init -> calling, sent offer ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1270 soa_get_local_sdp() soa_get_local_sdp(static::000000DB8F9933D0, [000000DB9064F780], [000000DB9064F790], [0000000000000000]) called ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:269 nua_stack_event() nua(000000DB8F7B77A0): event i_state INVITE sent ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2749 tport_wakeup_pri() tport_wakeup_pri(000000DB8F2E4C10): events IN ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2864 tport_recv_event() tport_recv_event(000000DB8F2E4C10) ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3205 tport_recv_iovec() tport(000000DB8F2E4C10) msg 000000DB8F5A2330 from (udp/10.10.101.238:5080) has 308 bytes, veclen = 1 recv 308 bytes from udp/[10.10.5.14]:1028 at 19:19:46.722506: ------------------------------------------------------------------------ SIP/2.0 100 Trying From:"SUPPORTSTREEMVM";tag=66mNXc2Z3m12B To: Call-ID:bae55b93-6f4b-1236-cf98-dd1cb2d72f2a CSeq:117383529 INVITE Server:PBX-IP Media Gateway/2.1 Via:SIP/2.0/UDP 10.10.101.238:5080;rport;branch=z9hG4bK5c1vgeDUmZHBa Content-Length:0 ------------------------------------------------------------------------ ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3023 tport_deliver() tport(000000DB8F2E4C10): msg 000000DB8F5A2330 (308 bytes) from udp/10.10.5.14:5080/sip next=0000000000000000 ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:3299 agent_recv_response() nta: received 100 Trying for INVITE (117383529) ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:3366 agent_recv_response() nta: 100 Trying is going to a transaction ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:9564 outgoing_estimate_delay() nta_outgoing: RTT is 17.007 ms ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:4222 tport_release() tport(000000DB8F2E4C10): 000000DB8F5A0CB0 by 000000DB8F3A5E50 with 000000DB8F5A2330 (preliminary) ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:1289 agent_timer() nta: timer not set ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2749 tport_wakeup_pri() tport_wakeup_pri(000000DB8F2E4C10): events IN ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2864 tport_recv_event() tport_recv_event(000000DB8F2E4C10) ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3205 tport_recv_iovec() tport(000000DB8F2E4C10) msg 000000DB8F5A08F0 from (udp/10.10.101.238:5080) has 556 bytes, veclen = 1 recv 556 bytes from udp/[10.10.5.14]:1028 at 19:19:47.763593: ------------------------------------------------------------------------ SIP/2.0 180 Ringing Reason:Q.931;progress_indicator=8;text="In-band information or an appropriate pattern is now available" Contact: Allow:INVITE,ACK,OPTIONS,BYE,CANCEL,REGISTER,INFO,PRACK,REFER,NOTIFY From:"SUPPORTSTREEMVM";tag=66mNXc2Z3m12B To:;tag=60DC324631353641000011D3 Call-ID:bae55b93-6f4b-1236-cf98-dd1cb2d72f2a CSeq:117383529 INVITE Server:PBX-IP Media Gateway/2.1 Via:SIP/2.0/UDP 10.10.101.238:5080;rport;branch=z9hG4bK5c1vgeDUmZHBa Content-Length:0 ------------------------------------------------------------------------ ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3023 tport_deliver() tport(000000DB8F2E4C10): msg 000000DB8F5A08F0 (556 bytes) from udp/10.10.5.14:5080/sip next=0000000000000000 ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:3299 agent_recv_response() nta: received 180 Ringing for INVITE (117383529) ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:3366 agent_recv_response() nta: 180 Ringing is going to a transaction ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:4222 tport_release() tport(000000DB8F2E4C10): 000000DB8F5A0CB0 by 000000DB8F3A5E50 with 000000DB8F5A08F0 (preliminary) ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(000000DB8F7B77A0): event r_invite 180 Ringing ..\..\sofia-sip\libsofia-sip-ua\nua\nua_session.c:4139 signal_call_state_change() nua(000000DB8F7B77A0): call state changed: calling -> proceeding ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(000000DB8F7B77A0): event i_state 180 Ringing 1790ebfe-b1a0-4833-9ef2-7b7d03fd6002 2018-01-08 13:19:47.759594 [NOTICE] sofia.c:7192 Ring-Ready sofia/external/18156548285! ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2749 tport_wakeup_pri() tport_wakeup_pri(000000DB8F2E4C10): events IN ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2864 tport_recv_event() tport_recv_event(000000DB8F2E4C10) ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3205 tport_recv_iovec() tport(000000DB8F2E4C10) msg 000000DB8F5A1070 from (udp/10.10.101.238:5080) has 451 bytes, veclen = 1 recv 451 bytes from udp/[10.10.5.14]:1028 at 19:19:49.785758: ------------------------------------------------------------------------ SIP/2.0 180 Ringing Contact: Allow:INVITE,ACK,OPTIONS,BYE,CANCEL,REGISTER,INFO,PRACK,REFER,NOTIFY From:"SUPPORTSTREEMVM";tag=66mNXc2Z3m12B To:;tag=60DC324631353641000011D3 Call-ID:bae55b93-6f4b-1236-cf98-dd1cb2d72f2a CSeq:117383529 INVITE Server:PBX-IP Media Gateway/2.1 Via:SIP/2.0/UDP 10.10.101.238:5080;rport;branch=z9hG4bK5c1vgeDUmZHBa Content-Length:0 ------------------------------------------------------------------------ ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3023 tport_deliver() tport(000000DB8F2E4C10): msg 000000DB8F5A1070 (451 bytes) from udp/10.10.5.14:5080/sip next=0000000000000000 ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:3299 agent_recv_response() nta: received 180 Ringing for INVITE (117383529) ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:3366 agent_recv_response() nta: 180 Ringing is going to a transaction ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:4222 tport_release() tport(000000DB8F2E4C10): 000000DB8F5A0CB0 by 000000DB8F3A5E50 with 000000DB8F5A1070 (preliminary) ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(000000DB8F7B77A0): event r_invite 180 Ringing ..\..\sofia-sip\libsofia-sip-ua\nua\nua_session.c:4139 signal_call_state_change() nua(000000DB8F7B77A0): call state changed: proceeding -> proceeding ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(000000DB8F7B77A0): event i_state 180 Ringing ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2749 tport_wakeup_pri() tport_wakeup_pri(000000DB8F2E4C10): events IN ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2864 tport_recv_event() tport_recv_event(000000DB8F2E4C10) ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3205 tport_recv_iovec() tport(000000DB8F2E4C10) msg 000000DB8F5A1F70 from (udp/10.10.101.238:5080) has 747 bytes, veclen = 1 recv 747 bytes from udp/[10.10.5.14]:1028 at 19:19:52.934016: ------------------------------------------------------------------------ SIP/2.0 200 OK Content-Type:application/sdp Supported:replaces,100rel Contact: Reason:E.182;text="Fax" Allow:INVITE,ACK,OPTIONS,BYE,CANCEL,REGISTER,INFO,PRACK,REFER,NOTIFY From:"SUPPORTSTREEMVM";tag=66mNXc2Z3m12B To:;tag=60DC324631353641000011D3 Call-ID:bae55b93-6f4b-1236-cf98-dd1cb2d72f2a CSeq:117383529 INVITE Server:PBX-IP Media Gateway/2.1 Via:SIP/2.0/UDP 10.10.101.238:5080;rport;branch=z9hG4bK5c1vgeDUmZHBa Content-Length:217 v=0 o=phone 14740 9006 IN IP4 10.10.5.14 s=- c=IN IP4 10.10.5.14 t=0 0 m=audio 49008 RTP/AVP 0 101 13 a=rtpmap:0 PCMU/8000/1 a=ptime:20 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=rtpmap:13 CN/8000 ------------------------------------------------------------------------ ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3023 tport_deliver() tport(000000DB8F2E4C10): msg 000000DB8F5A1F70 (747 bytes) from udp/10.10.5.14:5080/sip next=0000000000000000 ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:3299 agent_recv_response() nta: received 200 OK for INVITE (117383529) ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:3366 agent_recv_response() nta: 200 OK is going to a transaction ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:4222 tport_release() tport(000000DB8F2E4C10): 000000DB8F5A0CB0 by 000000DB8F3A5E50 with 000000DB8F5A1F70 ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:1350 set_timeout() nta: timer set to 32000 ms ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1171 soa_set_remote_sdp() soa_set_remote_sdp(static::000000DB8F9933D0, 0000000000000000, 000000DB90B3D222, 217) called ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1595 soa_process_answer() soa_process_answer(static::000000DB8F9933D0) called ..\..\sofia-sip\libsofia-sip-ua\soa\soa_static.c:1148 offer_answer_step() soa_static_offer_answer_action(000000DB8F9933D0, soa_process_answer): called ..\..\sofia-sip\libsofia-sip-ua\soa\soa_static.c:1029 soa_sdp_mode_set() soa_sdp_mode_set(000000DB8E4E62F0, 000000DB8F325C10, ""): called ..\..\sofia-sip\libsofia-sip-ua\soa\soa_static.c:1304 offer_answer_step() soa_static(000000DB8F9933D0, soa_process_answer): upgrade codecs with remote description ..\..\sofia-sip\libsofia-sip-ua\soa\soa_static.c:1446 offer_answer_step() soa_static(000000DB8F9933D0, soa_process_answer): storing local description ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1730 soa_activate() soa_activate(static::000000DB8F9933D0, (nil)) called ..\..\sofia-sip\libsofia-sip-ua\nua\nua_session.c:988 nua_session_client_response() nua(000000DB8F7B77A0): INVITE: processed SDP answer in 200 OK ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(000000DB8F7B77A0): event r_invite 200 OK ..\..\sofia-sip\libsofia-sip-ua\nua\nua_session.c:4139 signal_call_state_change() nua(000000DB8F7B77A0): call state changed: proceeding -> completing, received answer ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1098 soa_get_remote_sdp() soa_get_remote_sdp(static::000000DB8F9933D0, [000000DB9064F3B8], [000000DB9064F3A0], [0000000000000000]) called ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:616 soa_get_params() soa_get_params(static::000000DB8F9933D0, ...) called ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(000000DB8F7B77A0): event i_state 200 OK ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:529 nua_signal() nua(000000DB8F7B77A0): sent signal r_ack ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:569 nua_stack_signal() nua(000000DB8F7B77A0): recv signal r_ack ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:403 soa_set_params() soa_set_params(static::000000DB8F9933D0, ...) called ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1730 soa_activate() soa_activate(static::000000DB8F9933D0, (nil)) called ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:2665 nta_tpn_by_url() nta: selecting scheme sip ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3257 tport_tsend() tport_tsend(000000DB8F2E4C10) tpn = */10.10.5.14:5060 ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:4046 tport_resolve() tport_resolve addrinfo = 10.10.5.14:5060 ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:4680 tport_by_addrinfo() tport(000000DB8F2E4C10): not found by name */10.10.5.14:5060 ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3594 tport_vsend() tport_vsend(000000DB8F2E4C10): 428 bytes of 428 to udp/10.10.5.14:5060 ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3492 tport_send_msg() tport_vsend returned 428 send 428 bytes to udp/[10.10.5.14]:5060 at 19:19:52.943019: ------------------------------------------------------------------------ ACK sip:18156548285@10.10.5.14:5060 SIP/2.0 Via: SIP/2.0/UDP 10.10.101.238:5080;rport;branch=z9hG4bK6NtNj9XyH87XN Max-Forwards: 70 From: "SUPPORTSTREEMVM" ;tag=66mNXc2Z3m12B To: ;tag=60DC324631353641000011D3 Call-ID: bae55b93-6f4b-1236-cf98-dd1cb2d72f2a CSeq: 117383529 ACK Contact: Content-Length: 0 ------------------------------------------------------------------------ ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:8304 outgoing_send() nta: sent ACK (117383529) to */10.10.5.14:5060 ..\..\sofia-sip\libsofia-sip-ua\nua\nua_session.c:4139 signal_call_state_change() nua(000000DB8F7B77A0): call state changed: completing -> ready ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(000000DB8F7B77A0): event i_state 200 ACK sent ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(000000DB8F7B77A0): event i_active 200 Call active 1790ebfe-b1a0-4833-9ef2-7b7d03fd6002 2018-01-08 13:19:52.939019 [NOTICE] sofia.c:8218 Channel [sofia/external/18156548285] has been answered 1790ebfe-b1a0-4833-9ef2-7b7d03fd6002 2018-01-08 13:19:52.939019 [INFO] switch_channel.c:3129 sofia/external/18156548285 Flipping CID from "SUPPORTSTREEMVM" <8887344585> to "Outbound Call" <18156548285> ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2749 tport_wakeup_pri() tport_wakeup_pri(000000DB8F2E4C10): events IN ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2864 tport_recv_event() tport_recv_event(000000DB8F2E4C10) ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3205 tport_recv_iovec() tport(000000DB8F2E4C10) msg 000000DB8F5A08F0 from (udp/10.10.101.238:5080) has 865 bytes, veclen = 1 recv 865 bytes from udp/[10.10.5.14]:1028 at 19:19:52.964020: ------------------------------------------------------------------------ INVITE sip:gw+10.10.5.14@10.10.101.238:5080;transport=udp;gw=10.10.5.14 SIP/2.0 Content-Type:application/sdp Allow:INVITE,BYE,CANCEL,REFER,NOTIFY,OPTIONS,REGISTER,INFO,ACK,PRACK Supported:100rel,replaces Expires:120 To:"SUPPORTSTREEMVM";tag=66mNXc2Z3m12B From:;tag=60DC324631353641000011D3 Call-ID:bae55b93-6f4b-1236-cf98-dd1cb2d72f2a CSeq:1 INVITE Max-Forwards:70 User-Agent:PBX-IP Media Gateway Contact: Via:SIP/2.0/UDP 10.10.5.14:5060;branch=z9hG4bK610FFE624F10D6EE40DC26B4FCE75E24 Content-Length:263 v=0 o=phone 14740 9007 IN IP4 10.10.5.14 s=- c=IN IP4 10.10.5.14 t=0 0 m=image 49008 udptl t38 a=T38FaxMaxBuffer:1800 a=T38FaxMaxDatagram:150 a=T38FaxVersion:0 a=T38MaxBitRate:14400 a=T38FaxRateManagement:transferredTCF a=T38FaxUdpEC:t38UDPRedundancy ------------------------------------------------------------------------ ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3023 tport_deliver() tport(000000DB8F2E4C10): msg 000000DB8F5A08F0 (865 bytes) from udp/10.10.5.14:5080/sip next=0000000000000000 ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:2880 agent_recv_request() nta: received INVITE sip:gw+10.10.5.14@10.10.101.238:5080;transport=udp;gw=10.10.5.14 SIP/2.0 (CSeq 1) ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:3248 agent_aliases() nta: canonizing sip:gw+10.10.5.14@10.10.101.238:5080 with contact ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:3060 agent_recv_request() nta: INVITE (1) going to existing leg ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:1348 set_timeout() nta: timer shortened to 200 ms ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1302 soa_init_offer_answer() soa_init_offer_answer(static::000000DB8F9933D0) called ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1171 soa_set_remote_sdp() soa_set_remote_sdp(static::000000DB8F9933D0, 0000000000000000, 000000DB8F9ADC8A, 263) called ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3257 tport_tsend() tport_tsend(000000DB8F2E4C10) tpn = UDP/10.10.5.14:5060 ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:4046 tport_resolve() tport_resolve addrinfo = 10.10.5.14:5060 ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:4680 tport_by_addrinfo() tport(000000DB8F2E4C10): not found by name UDP/10.10.5.14:5060 ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3594 tport_vsend() tport_vsend(000000DB8F2E4C10): 386 bytes of 386 to udp/10.10.5.14:5060 ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3492 tport_send_msg() tport_vsend returned 386 send 386 bytes to udp/[10.10.5.14]:5060 at 19:19:52.965022: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via:SIP/2.0/UDP 10.10.5.14:5060;branch=z9hG4bK610FFE624F10D6EE40DC26B4FCE75E24 From:;tag=60DC324631353641000011D3 To:"SUPPORTSTREEMVM";tag=66mNXc2Z3m12B Call-ID:bae55b93-6f4b-1236-cf98-dd1cb2d72f2a CSeq:1 INVITE User-Agent: FreeSWITCH-mod_sofia/1.6.19+git~20171109T180403Z~27aebc7253~64bit Content-Length: 0 ------------------------------------------------------------------------ ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:6791 incoming_reply() nta: sent 100 Trying for INVITE (1) ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(000000DB8F7B77A0): event i_invite 100 Trying ..\..\sofia-sip\libsofia-sip-ua\nua\nua_session.c:4145 signal_call_state_change() nua(000000DB8F7B77A0): ready call updated: received received offer ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1098 soa_get_remote_sdp() soa_get_remote_sdp(static::000000DB8F9933D0, [000000DB9064F288], [000000DB9064F270], [0000000000000000]) called ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(000000DB8F7B77A0): event i_state 100 Trying ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:1296 agent_timer() nta: timer set next to 31769 ms ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:529 nua_signal() nua(000000DB8F7B77A0): sent signal r_respond ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:573 nua_stack_signal() nua(000000DB8F7B77A0): recv signal r_respond 200 OK ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:403 soa_set_params() soa_set_params(static::000000DB8F9933D0, ...) called ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1052 soa_set_user_sdp() soa_set_user_sdp(static::000000DB8F9933D0, 0000000000000000, 000000DB8E266E00, -1) called ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1515 soa_generate_answer() soa_generate_answer(static::000000DB8F9933D0) called ..\..\sofia-sip\libsofia-sip-ua\soa\soa_static.c:1148 offer_answer_step() soa_static_offer_answer_action(000000DB8F9933D0, soa_generate_answer): called ..\..\sofia-sip\libsofia-sip-ua\soa\soa_static.c:1230 offer_answer_step() soa_static(000000DB8F9933D0, soa_generate_answer): upgrade with remote description ..\..\sofia-sip\libsofia-sip-ua\soa\soa_static.c:1029 soa_sdp_mode_set() soa_sdp_mode_set(000000DB9064D760, 000000DB8F9AC220, ""): called ..\..\sofia-sip\libsofia-sip-ua\soa\soa_static.c:1446 offer_answer_step() soa_static(000000DB8F9933D0, soa_generate_answer): storing local description ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1730 soa_activate() soa_activate(static::000000DB8F9933D0, (nil)) called ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1270 soa_get_local_sdp() soa_get_local_sdp(static::000000DB8F9933D0, [0000000000000000], [000000DB9064F8B0], [000000DB9064F8E8]) called ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3257 tport_tsend() tport_tsend(000000DB8F2E4C10) tpn = UDP/10.10.5.14:5060 ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:4046 tport_resolve() tport_resolve addrinfo = 10.10.5.14:5060 ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:4680 tport_by_addrinfo() tport(000000DB8F2E4C10): not found by name UDP/10.10.5.14:5060 ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3594 tport_vsend() tport_vsend(000000DB8F2E4C10): 965 bytes of 965 to udp/10.10.5.14:5060 ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3492 tport_send_msg() tport_vsend returned 965 send 965 bytes to udp/[10.10.5.14]:5060 at 19:19:53.239043: ------------------------------------------------------------------------ SIP/2.0 200 OK Via:SIP/2.0/UDP 10.10.5.14:5060;branch=z9hG4bK610FFE624F10D6EE40DC26B4FCE75E24 From:;tag=60DC324631353641000011D3 To:"SUPPORTSTREEMVM";tag=66mNXc2Z3m12B Call-ID:bae55b93-6f4b-1236-cf98-dd1cb2d72f2a CSeq:1 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/1.6.19+git~20171109T180403Z~27aebc7253~64bit Accept: application/sdp Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY Supported: timer, path, replaces Content-Type: application/sdp Content-Disposition: session Content-Length: 294 v=0 o=FreeSWITCH 1515382836 1515382838 IN IP4 10.10.101.238 s=FreeSWITCH c=IN IP4 10.10.101.238 t=0 0 m=image 56350 udptl t38 a=T38FaxVersion:0 a=T38MaxBitRate:14400 a=T38FaxRateManagement:transferredTCF a=T38FaxMaxBuffer:1800 a=T38FaxMaxDatagram:400 a=T38FaxUdpEC:t38UDPRedundancy ------------------------------------------------------------------------ ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:6791 incoming_reply() nta: sent 200 OK for INVITE (1) ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:1348 set_timeout() nta: timer shortened to 500 ms ..\..\sofia-sip\libsofia-sip-ua\nua\nua_session.c:4145 signal_call_state_change() nua(000000DB8F7B77A0): ready call updated: completed sent answer ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1270 soa_get_local_sdp() soa_get_local_sdp(static::000000DB8F9933D0, [000000DB9064F960], [000000DB9064F970], [0000000000000000]) called ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:616 soa_get_params() soa_get_params(static::000000DB8F9933D0, ...) called ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(000000DB8F7B77A0): event i_state 200 OK ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2749 tport_wakeup_pri() tport_wakeup_pri(000000DB8F2E4C10): events IN ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2864 tport_recv_event() tport_recv_event(000000DB8F2E4C10) ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3205 tport_recv_iovec() tport(000000DB8F2E4C10) msg 000000DB8F5A1430 from (udp/10.10.101.238:5080) has 411 bytes, veclen = 1 recv 411 bytes from udp/[10.10.5.14]:1028 at 19:19:53.251052: ------------------------------------------------------------------------ ACK sip:gw+10.10.5.14@10.10.101.238:5080;transport=udp;gw=10.10.5.14 SIP/2.0 CSeq:1 ACK To:"SUPPORTSTREEMVM";tag=66mNXc2Z3m12B From:;tag=60DC324631353641000011D3 Call-ID:bae55b93-6f4b-1236-cf98-dd1cb2d72f2a Max-Forwards:70 User-Agent:PBX-IP Media Gateway Via:SIP/2.0/UDP 10.10.5.14:5060;branch=z9hG4bK75D99919404F6DB024F717EADF7AA9F4 Content-Length:0 ------------------------------------------------------------------------ ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3023 tport_deliver() tport(000000DB8F2E4C10): msg 000000DB8F5A1430 (411 bytes) from udp/10.10.5.14:5080/sip next=0000000000000000 ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:2880 agent_recv_request() nta: received ACK sip:gw+10.10.5.14@10.10.101.238:5080;transport=udp;gw=10.10.5.14 SIP/2.0 (CSeq 1) ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:3019 agent_recv_request() nta: ACK (1) is going to INVITE (1) ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1214 soa_clear_remote_sdp() soa_clear_remote_sdp(static::000000DB8F9933D0) called ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(000000DB8F7B77A0): event i_ack 200 OK ..\..\sofia-sip\libsofia-sip-ua\nua\nua_session.c:4145 signal_call_state_change() nua(000000DB8F7B77A0): ready call updated: ready ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(000000DB8F7B77A0): event i_state 200 OK ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(000000DB8F7B77A0): event i_active 200 Call active ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:1296 agent_timer() nta: timer set next to 4512 ms ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:7134 _nta_incoming_timer() nta: timer I fired, terminate 200 response ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:5825 incoming_reclaim_queued() incoming_reclaim_all(0000000000000000, 0000000000000000, 000000DB9064FA40) ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:7188 _nta_incoming_timer() nta_incoming_timer: 0/0 resent, 0/0 tout, 1/1 term, 1/1 free ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:1296 agent_timer() nta: timer set next to 26683 ms ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:9101 outgoing_timer_dk() nta: timer D fired, terminate INVITE (117383529) ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:8799 outgoing_reclaim_queued() outgoing_reclaim_all(0000000000000000, 0000000000000000, 000000DB9064FA40) ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:8929 _nta_outgoing_timer() nta_outgoing_timer: 0/0 resent, 0/1 tout, 1/1 term, 1/2 free ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:1296 agent_timer() nta: timer set next to 9 ms ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:8982 outgoing_timer_bf() nta: timer F fired, terminating ACK (117383529) ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:8799 outgoing_reclaim_queued() outgoing_reclaim_all(0000000000000000, 0000000000000000, 000000DB9064FA40) ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:8929 _nta_outgoing_timer() nta_outgoing_timer: 0/0 resent, 1/1 tout, 0/0 term, 1/1 free ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:1289 agent_timer() nta: timer not set ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2749 tport_wakeup_pri() tport_wakeup_pri(000000DB8F2E4C10): events IN ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2864 tport_recv_event() tport_recv_event(000000DB8F2E4C10) ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3205 tport_recv_iovec() tport(000000DB8F2E4C10) msg 000000DB8F5A1F70 from (udp/10.10.101.238:5080) has 490 bytes, veclen = 1 recv 490 bytes from udp/[10.10.5.14]:1028 at 19:20:51.489871: ------------------------------------------------------------------------ BYE sip:gw+10.10.5.14@10.10.101.238:5080;transport=udp;gw=10.10.5.14 SIP/2.0 Reason:E.182;text="Normal" Reason:Q.850;cause=16;text="Normal call clearing" To:"SUPPORTSTREEMVM";tag=66mNXc2Z3m12B From:;tag=60DC324631353641000011D3 Call-ID:bae55b93-6f4b-1236-cf98-dd1cb2d72f2a CSeq:2 BYE Max-Forwards:70 User-Agent:PBX-IP Media Gateway Via:SIP/2.0/UDP 10.10.5.14:5060;branch=z9hG4bK2C988DEFAE63B5E00C2E0F4DF5E43ACB Content-Length:0 ------------------------------------------------------------------------ ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3023 tport_deliver() tport(000000DB8F2E4C10): msg 000000DB8F5A1F70 (490 bytes) from udp/10.10.5.14:5080/sip next=0000000000000000 ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:2880 agent_recv_request() nta: received BYE sip:gw+10.10.5.14@10.10.101.238:5080;transport=udp;gw=10.10.5.14 SIP/2.0 (CSeq 2) ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:3248 agent_aliases() nta: canonizing sip:gw+10.10.5.14@10.10.101.238:5080 with contact ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:3060 agent_recv_request() nta: BYE (2) going to existing leg ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(000000DB8F7B77A0): event i_bye 100 Trying 1790ebfe-b1a0-4833-9ef2-7b7d03fd6002 2018-01-08 13:20:51.478878 [NOTICE] sofia.c:1012 Hangup sofia/external/18156548285 [CS_EXECUTE] [NORMAL_CLEARING] ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:529 nua_signal() nua(000000DB8F7B77A0): sent signal r_respond ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:529 nua_signal() nua(000000DB8F7B77A0): sent signal r_destroy ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:573 nua_stack_signal() nua(000000DB8F7B77A0): recv signal r_respond 200 OK ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:403 soa_set_params() soa_set_params(static::000000DB8F9933D0, ...) called ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3257 tport_tsend() tport_tsend(000000DB8F2E4C10) tpn = UDP/10.10.5.14:5060 ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:4046 tport_resolve() tport_resolve addrinfo = 10.10.5.14:5060 ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:4680 tport_by_addrinfo() tport(000000DB8F2E4C10): not found by name UDP/10.10.5.14:5060 ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3594 tport_vsend() tport_vsend(000000DB8F2E4C10): 503 bytes of 503 to udp/10.10.5.14:5060 ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3492 tport_send_msg() tport_vsend returned 503 send 503 bytes to udp/[10.10.5.14]:5060 at 19:20:51.491875: ------------------------------------------------------------------------ SIP/2.0 200 OK Via:SIP/2.0/UDP 10.10.5.14:5060;branch=z9hG4bK2C988DEFAE63B5E00C2E0F4DF5E43ACB From:;tag=60DC324631353641000011D3 To:"SUPPORTSTREEMVM";tag=66mNXc2Z3m12B Call-ID:bae55b93-6f4b-1236-cf98-dd1cb2d72f2a CSeq:2 BYE User-Agent: FreeSWITCH-mod_sofia/1.6.19+git~20171109T180403Z~27aebc7253~64bit Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY Supported: timer, path, replaces Content-Length: 0 ------------------------------------------------------------------------ ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:6791 incoming_reply() nta: sent 200 OK for BYE (2) ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:1350 set_timeout() nta: timer set to 32000 ms ..\..\sofia-sip\libsofia-sip-ua\nua\nua_dialog.c:397 nua_dialog_usage_remove_at() nua(000000DB8F7B77A0): removing session usage ..\..\sofia-sip\libsofia-sip-ua\nua\nua_session.c:4139 signal_call_state_change() nua(000000DB8F7B77A0): call state changed: ready -> terminated ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(000000DB8F7B77A0): event i_state 200 Session Terminated ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(000000DB8F7B77A0): event i_terminated 200 Session Terminated ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:356 soa_destroy() soa_destroy(static::000000DB8F9933D0) called ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:4470 nta_leg_destroy() nta_leg_destroy(000000DB8F7AED90) ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:569 nua_stack_signal() nua(000000DB8F7B77A0): recv signal r_destroy ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:4470 nta_leg_destroy() nta_leg_destroy(0000000000000000) 1790ebfe-b1a0-4833-9ef2-7b7d03fd6002 2018-01-08 13:20:51.478878 [NOTICE] switch_core_session.c:1683 Session 19 (sofia/external/18156548285) Ended 1790ebfe-b1a0-4833-9ef2-7b7d03fd6002 2018-01-08 13:20:51.478878 [NOTICE] switch_core_session.c:1687 Close Channel sofia/external/18156548285 [CS_DESTROY] d4b0f76f-e30b-4180-b12b-54d8c1fa0ea5 2018-01-08 13:21:17.038770 [NOTICE] switch_channel.c:1104 New Channel sofia/external/18156548285 [d4b0f76f-e30b-4180-b12b-54d8c1fa0ea5] ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:529 nua_signal() nua(000000DB8F7B6A20): sent signal r_invite ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:569 nua_stack_signal() nua(000000DB8F7B6A20): recv signal r_invite ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:280 soa_clone() soa_clone(static::000000DB8F2E3F60, 000000DB8F5301B0, 000000DB8F7B6A20) called ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:403 soa_set_params() soa_set_params(static::000000DB8F992C50, ...) called ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:403 soa_set_params() soa_set_params(static::000000DB8F992C50, ...) called ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1052 soa_set_user_sdp() soa_set_user_sdp(static::000000DB8F992C50, 0000000000000000, 000000DB8F324987, -1) called ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:890 soa_set_capability_sdp() soa_set_capability_sdp(static::000000DB8F992C50, 0000000000000000, 000000DB8F324987, -1) called ..\..\sofia-sip\libsofia-sip-ua\nua\nua_dialog.c:338 nua_dialog_usage_add() nua(000000DB8F7B6A20): adding session usage ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:4417 nta_leg_tcreate() nta_leg_tcreate(000000DB90D6D8C0) ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1302 soa_init_offer_answer() soa_init_offer_answer(static::000000DB8F992C50) called ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1426 soa_generate_offer() soa_generate_offer(static::000000DB8F992C50, 0) called ..\..\sofia-sip\libsofia-sip-ua\soa\soa_static.c:1148 offer_answer_step() soa_static_offer_answer_action(000000DB8F992C50, soa_generate_offer): called ..\..\sofia-sip\libsofia-sip-ua\soa\soa_static.c:1189 offer_answer_step() soa_static(000000DB8F992C50, soa_generate_offer): generating local description ..\..\sofia-sip\libsofia-sip-ua\soa\soa_static.c:1217 offer_answer_step() soa_static(000000DB8F992C50, soa_generate_offer): upgrade with local description ..\..\sofia-sip\libsofia-sip-ua\soa\soa_static.c:1029 soa_sdp_mode_set() soa_sdp_mode_set(000000DB9064D660, 0000000000000000, ""): called ..\..\sofia-sip\libsofia-sip-ua\soa\soa_static.c:1446 offer_answer_step() soa_static(000000DB8F992C50, soa_generate_offer): storing local description ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1270 soa_get_local_sdp() soa_get_local_sdp(static::000000DB8F992C50, [0000000000000000], [000000DB9064F7B0], [000000DB9064F7E8]) called ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:2665 nta_tpn_by_url() nta: selecting scheme sip ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3257 tport_tsend() tport_tsend(000000DB8F2E4C10) tpn = */10.10.5.14:5060 ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:4046 tport_resolve() tport_resolve addrinfo = 10.10.5.14:5060 ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:4680 tport_by_addrinfo() tport(000000DB8F2E4C10): not found by name */10.10.5.14:5060 ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3594 tport_vsend() tport_vsend(000000DB8F2E4C10): 1145 bytes of 1145 to udp/10.10.5.14:5060 ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3492 tport_send_msg() tport_vsend returned 1145 send 1145 bytes to udp/[10.10.5.14]:5060 at 19:21:17.049785: ------------------------------------------------------------------------ INVITE sip:18156548285@10.10.5.14 SIP/2.0 Via: SIP/2.0/UDP 10.10.101.238:5080;rport;branch=z9hG4bK7yKem4e2eHygH Max-Forwards: 70 From: "SUPPORTSTREEMVM" ;tag=7FeeZ7j30XQNQ To: Call-ID: f0becd40-6f4b-1236-cf98-dd1cb2d72f2a CSeq: 117383574 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/1.6.19+git~20171109T180403Z~27aebc7253~64bit 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: 293 X-FS-Support: update_display,send_info Remote-Party-ID: "SUPPORTSTREEMVM" ;party=calling;screen=yes;privacy=off v=0 o=FreeSWITCH 1515383223 1515383224 IN IP4 10.10.101.238 s=FreeSWITCH c=IN IP4 10.10.101.238 t=0 0 m=audio 56054 RTP/AVP 0 8 3 101 13 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:3 GSM/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=rtpmap:13 CN/8000 a=ptime:20 ------------------------------------------------------------------------ ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:8304 outgoing_send() nta: sent INVITE (117383574) to */10.10.5.14:5060 ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:4160 tport_pend() tport_pend(000000DB8F2E4C10): pending 000000DB8F5A08F0 for udp/10.10.101.238:5080 (already 0) ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:1348 set_timeout() nta: timer shortened to 1000 ms ..\..\sofia-sip\libsofia-sip-ua\nua\nua_session.c:4139 signal_call_state_change() nua(000000DB8F7B6A20): call state changed: init -> calling, sent offer ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1270 soa_get_local_sdp() soa_get_local_sdp(static::000000DB8F992C50, [000000DB9064F780], [000000DB9064F790], [0000000000000000]) called ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:269 nua_stack_event() nua(000000DB8F7B6A20): event i_state INVITE sent ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2749 tport_wakeup_pri() tport_wakeup_pri(000000DB8F2E4C10): events IN ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2864 tport_recv_event() tport_recv_event(000000DB8F2E4C10) ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3205 tport_recv_iovec() tport(000000DB8F2E4C10) msg 000000DB8F5A1430 from (udp/10.10.101.238:5080) has 308 bytes, veclen = 1 recv 308 bytes from udp/[10.10.5.14]:1028 at 19:21:17.064778: ------------------------------------------------------------------------ SIP/2.0 100 Trying From:"SUPPORTSTREEMVM";tag=7FeeZ7j30XQNQ To: Call-ID:f0becd40-6f4b-1236-cf98-dd1cb2d72f2a CSeq:117383574 INVITE Server:PBX-IP Media Gateway/2.1 Via:SIP/2.0/UDP 10.10.101.238:5080;rport;branch=z9hG4bK7yKem4e2eHygH Content-Length:0 ------------------------------------------------------------------------ ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3023 tport_deliver() tport(000000DB8F2E4C10): msg 000000DB8F5A1430 (308 bytes) from udp/10.10.5.14:5080/sip next=0000000000000000 ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:3299 agent_recv_response() nta: received 100 Trying for INVITE (117383574) ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:3366 agent_recv_response() nta: 100 Trying is going to a transaction ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:9564 outgoing_estimate_delay() nta_outgoing: RTT is 14.993 ms ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:4222 tport_release() tport(000000DB8F2E4C10): 000000DB8F5A08F0 by 000000DB8F3A6390 with 000000DB8F5A1430 (preliminary) fb05e962-059f-4bb4-b9e1-54130ed0b2e3 2018-01-08 13:21:17.078782 [NOTICE] switch_channel.c:1104 New Channel sofia/external/18156548285 [fb05e962-059f-4bb4-b9e1-54130ed0b2e3] ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:529 nua_signal() nua(000000DB8F7B7200): sent signal r_invite ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:569 nua_stack_signal() nua(000000DB8F7B7200): recv signal r_invite ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:280 soa_clone() soa_clone(static::000000DB8F2E3F60, 000000DB8F5301B0, 000000DB8F7B7200) called ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:403 soa_set_params() soa_set_params(static::000000DB8F9933D0, ...) called ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:403 soa_set_params() soa_set_params(static::000000DB8F9933D0, ...) called ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1052 soa_set_user_sdp() soa_set_user_sdp(static::000000DB8F9933D0, 0000000000000000, 000000DB8F324667, -1) called ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:890 soa_set_capability_sdp() soa_set_capability_sdp(static::000000DB8F9933D0, 0000000000000000, 000000DB8F324667, -1) called ..\..\sofia-sip\libsofia-sip-ua\nua\nua_dialog.c:338 nua_dialog_usage_add() nua(000000DB8F7B7200): adding session usage ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:4417 nta_leg_tcreate() nta_leg_tcreate(000000DB90D6DBC0) ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1302 soa_init_offer_answer() soa_init_offer_answer(static::000000DB8F9933D0) called ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1426 soa_generate_offer() soa_generate_offer(static::000000DB8F9933D0, 0) called ..\..\sofia-sip\libsofia-sip-ua\soa\soa_static.c:1148 offer_answer_step() soa_static_offer_answer_action(000000DB8F9933D0, soa_generate_offer): called ..\..\sofia-sip\libsofia-sip-ua\soa\soa_static.c:1189 offer_answer_step() soa_static(000000DB8F9933D0, soa_generate_offer): generating local description ..\..\sofia-sip\libsofia-sip-ua\soa\soa_static.c:1217 offer_answer_step() soa_static(000000DB8F9933D0, soa_generate_offer): upgrade with local description ..\..\sofia-sip\libsofia-sip-ua\soa\soa_static.c:1029 soa_sdp_mode_set() soa_sdp_mode_set(000000DB9064D660, 0000000000000000, ""): called ..\..\sofia-sip\libsofia-sip-ua\soa\soa_static.c:1446 offer_answer_step() soa_static(000000DB8F9933D0, soa_generate_offer): storing local description ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1270 soa_get_local_sdp() soa_get_local_sdp(static::000000DB8F9933D0, [0000000000000000], [000000DB9064F7B0], [000000DB9064F7E8]) called ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:2665 nta_tpn_by_url() nta: selecting scheme sip ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3257 tport_tsend() tport_tsend(000000DB8F2E4C10) tpn = */10.10.5.14:5060 ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:4046 tport_resolve() tport_resolve addrinfo = 10.10.5.14:5060 ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:4680 tport_by_addrinfo() tport(000000DB8F2E4C10): not found by name */10.10.5.14:5060 ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3594 tport_vsend() tport_vsend(000000DB8F2E4C10): 1145 bytes of 1145 to udp/10.10.5.14:5060 ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3492 tport_send_msg() tport_vsend returned 1145 send 1145 bytes to udp/[10.10.5.14]:5060 at 19:21:17.084775: ------------------------------------------------------------------------ INVITE sip:18156548285@10.10.5.14 SIP/2.0 Via: SIP/2.0/UDP 10.10.101.238:5080;rport;branch=z9hG4bK87c7NZZ5Btm3c Max-Forwards: 70 From: "SUPPORTSTREEMVM" ;tag=8r760236X6D8j To: Call-ID: f0c42408-6f4b-1236-cf98-dd1cb2d72f2a CSeq: 117383574 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/1.6.19+git~20171109T180403Z~27aebc7253~64bit 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: 293 X-FS-Support: update_display,send_info Remote-Party-ID: "SUPPORTSTREEMVM" ;party=calling;screen=yes;privacy=off v=0 o=FreeSWITCH 1515382535 1515382536 IN IP4 10.10.101.238 s=FreeSWITCH c=IN IP4 10.10.101.238 t=0 0 m=audio 56742 RTP/AVP 0 8 3 101 13 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:3 GSM/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=rtpmap:13 CN/8000 a=ptime:20 ------------------------------------------------------------------------ ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:8304 outgoing_send() nta: sent INVITE (117383574) to */10.10.5.14:5060 ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:4160 tport_pend() tport_pend(000000DB8F2E4C10): pending 000000DB8F5A0CB0 for udp/10.10.101.238:5080 (already 1) ..\..\sofia-sip\libsofia-sip-ua\nua\nua_session.c:4139 signal_call_state_change() nua(000000DB8F7B7200): call state changed: init -> calling, sent offer ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1270 soa_get_local_sdp() soa_get_local_sdp(static::000000DB8F9933D0, [000000DB9064F780], [000000DB9064F790], [0000000000000000]) called ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:269 nua_stack_event() nua(000000DB8F7B7200): event i_state INVITE sent ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2749 tport_wakeup_pri() tport_wakeup_pri(000000DB8F2E4C10): events IN ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2864 tport_recv_event() tport_recv_event(000000DB8F2E4C10) ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3205 tport_recv_iovec() tport(000000DB8F2E4C10) msg 000000DB8F5A1BB0 from (udp/10.10.101.238:5080) has 308 bytes, veclen = 1 recv 308 bytes from udp/[10.10.5.14]:1028 at 19:21:17.099941: ------------------------------------------------------------------------ SIP/2.0 100 Trying From:"SUPPORTSTREEMVM";tag=8r760236X6D8j To: Call-ID:f0c42408-6f4b-1236-cf98-dd1cb2d72f2a CSeq:117383574 INVITE Server:PBX-IP Media Gateway/2.1 Via:SIP/2.0/UDP 10.10.101.238:5080;rport;branch=z9hG4bK87c7NZZ5Btm3c Content-Length:0 ------------------------------------------------------------------------ ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3023 tport_deliver() tport(000000DB8F2E4C10): msg 000000DB8F5A1BB0 (308 bytes) from udp/10.10.5.14:5080/sip next=0000000000000000 ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:3299 agent_recv_response() nta: received 100 Trying for INVITE (117383574) ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:3366 agent_recv_response() nta: 100 Trying is going to a transaction ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:9564 outgoing_estimate_delay() nta_outgoing: RTT is 15.166 ms ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:4222 tport_release() tport(000000DB8F2E4C10): 000000DB8F5A0CB0 by 000000DB8F3A6630 with 000000DB8F5A1BB0 (preliminary) ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2749 tport_wakeup_pri() tport_wakeup_pri(000000DB8F2E4C10): events IN ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2864 tport_recv_event() tport_recv_event(000000DB8F2E4C10) ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3205 tport_recv_iovec() tport(000000DB8F2E4C10) msg 000000DB8F5A1BB0 from (udp/10.10.101.238:5080) has 556 bytes, veclen = 1 recv 556 bytes from udp/[10.10.5.14]:1028 at 19:21:17.993843: ------------------------------------------------------------------------ SIP/2.0 180 Ringing Reason:Q.931;progress_indicator=8;text="In-band information or an appropriate pattern is now available" Contact: Allow:INVITE,ACK,OPTIONS,BYE,CANCEL,REGISTER,INFO,PRACK,REFER,NOTIFY From:"SUPPORTSTREEMVM";tag=8r760236X6D8j To:;tag=5EE13246313536410000155C Call-ID:f0c42408-6f4b-1236-cf98-dd1cb2d72f2a CSeq:117383574 INVITE Server:PBX-IP Media Gateway/2.1 Via:SIP/2.0/UDP 10.10.101.238:5080;rport;branch=z9hG4bK87c7NZZ5Btm3c Content-Length:0 ------------------------------------------------------------------------ ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3023 tport_deliver() tport(000000DB8F2E4C10): msg 000000DB8F5A1BB0 (556 bytes) from udp/10.10.5.14:5080/sip next=0000000000000000 ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:3299 agent_recv_response() nta: received 180 Ringing for INVITE (117383574) ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:3366 agent_recv_response() nta: 180 Ringing is going to a transaction ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:4222 tport_release() tport(000000DB8F2E4C10): 000000DB8F5A0CB0 by 000000DB8F3A6630 with 000000DB8F5A1BB0 (preliminary) ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(000000DB8F7B7200): event r_invite 180 Ringing ..\..\sofia-sip\libsofia-sip-ua\nua\nua_session.c:4139 signal_call_state_change() nua(000000DB8F7B7200): call state changed: calling -> proceeding ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(000000DB8F7B7200): event i_state 180 Ringing fb05e962-059f-4bb4-b9e1-54130ed0b2e3 2018-01-08 13:21:17.998851 [NOTICE] sofia.c:7192 Ring-Ready sofia/external/18156548285! ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:1296 agent_timer() nta: timer set next to 5442 ms ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2749 tport_wakeup_pri() tport_wakeup_pri(000000DB8F2E4C10): events IN ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2864 tport_recv_event() tport_recv_event(000000DB8F2E4C10) ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3205 tport_recv_iovec() tport(000000DB8F2E4C10) msg 000000DB8E267960 from (udp/10.10.101.238:5080) has 411 bytes, veclen = 1 recv 411 bytes from udp/[10.10.5.14]:1028 at 19:21:19.179927: ------------------------------------------------------------------------ SIP/2.0 486 Busy Here Reason:E.182;text="User Busy" Reason:Q.850;cause=17;text="User busy" From:"SUPPORTSTREEMVM";tag=7FeeZ7j30XQNQ To:;tag=1D6C3246313536410000155B Call-ID:f0becd40-6f4b-1236-cf98-dd1cb2d72f2a CSeq:117383574 INVITE Server:PBX-IP Media Gateway/2.1 Via:SIP/2.0/UDP 10.10.101.238:5080;rport;branch=z9hG4bK7yKem4e2eHygH Content-Length:0 ------------------------------------------------------------------------ ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3023 tport_deliver() tport(000000DB8F2E4C10): msg 000000DB8E267960 (411 bytes) from udp/10.10.5.14:5080/sip next=0000000000000000 ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:3299 agent_recv_response() nta: received 486 Busy Here for INVITE (117383574) ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:3366 agent_recv_response() nta: 486 Busy Here is going to a transaction ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:4222 tport_release() tport(000000DB8F2E4C10): 000000DB8F5A08F0 by 000000DB8F3A6390 with 000000DB8E267960 ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3257 tport_tsend() tport_tsend(000000DB8F2E4C10) tpn = UDP/10.10.5.14:5060 ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:4046 tport_resolve() tport_resolve addrinfo = 10.10.5.14:5060 ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:4680 tport_by_addrinfo() tport(000000DB8F2E4C10): not found by name UDP/10.10.5.14:5060 ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3594 tport_vsend() tport_vsend(000000DB8F2E4C10): 346 bytes of 346 to udp/10.10.5.14:5060 ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3492 tport_send_msg() tport_vsend returned 346 send 346 bytes to udp/[10.10.5.14]:5060 at 19:21:19.179927: ------------------------------------------------------------------------ ACK sip:18156548285@10.10.5.14 SIP/2.0 Via: SIP/2.0/UDP 10.10.101.238:5080;rport;branch=z9hG4bK7yKem4e2eHygH Max-Forwards: 70 From: "SUPPORTSTREEMVM" ;tag=7FeeZ7j30XQNQ To: ;tag=1D6C3246313536410000155B Call-ID: f0becd40-6f4b-1236-cf98-dd1cb2d72f2a CSeq: 117383574 ACK Content-Length: 0 ------------------------------------------------------------------------ ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:8304 outgoing_send() nta: sent ACK (117383574) to UDP/10.10.5.14:5060 ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:8722 outgoing_free() nta: outgoing_free(000000DB8F3A6A20) ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(000000DB8F7B6A20): event r_invite 486 Busy Here ..\..\sofia-sip\libsofia-sip-ua\nua\nua_session.c:4139 signal_call_state_change() nua(000000DB8F7B6A20): call state changed: calling -> init ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(000000DB8F7B6A20): event i_state 486 Busy Here ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(000000DB8F7B6A20): event i_terminated 486 Busy Here ..\..\sofia-sip\libsofia-sip-ua\nua\nua_dialog.c:397 nua_dialog_usage_remove_at() nua(000000DB8F7B6A20): removing session usage ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:356 soa_destroy() soa_destroy(static::000000DB8F992C50) called ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:4470 nta_leg_destroy() nta_leg_destroy(000000DB90D6D8C0) d4b0f76f-e30b-4180-b12b-54d8c1fa0ea5 2018-01-08 13:21:19.178955 [NOTICE] sofia.c:8273 Hangup sofia/external/18156548285 [CS_CONSUME_MEDIA] [USER_BUSY] ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:529 nua_signal() nua(000000DB8F7B6A20): sent signal r_destroy ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:569 nua_stack_signal() nua(000000DB8F7B6A20): recv signal r_destroy ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:4470 nta_leg_destroy() nta_leg_destroy(0000000000000000) d4b0f76f-e30b-4180-b12b-54d8c1fa0ea5 2018-01-08 13:21:19.178955 [NOTICE] switch_core_session.c:1683 Session 20 (sofia/external/18156548285) Ended d4b0f76f-e30b-4180-b12b-54d8c1fa0ea5 2018-01-08 13:21:19.178955 [NOTICE] switch_core_session.c:1687 Close Channel sofia/external/18156548285 [CS_DESTROY] ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2749 tport_wakeup_pri() tport_wakeup_pri(000000DB8F2E4C10): events IN ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2864 tport_recv_event() tport_recv_event(000000DB8F2E4C10) ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3205 tport_recv_iovec() tport(000000DB8F2E4C10) msg 000000DB8E2671E0 from (udp/10.10.101.238:5080) has 451 bytes, veclen = 1 recv 451 bytes from udp/[10.10.5.14]:1028 at 19:21:20.029990: ------------------------------------------------------------------------ SIP/2.0 180 Ringing Contact: Allow:INVITE,ACK,OPTIONS,BYE,CANCEL,REGISTER,INFO,PRACK,REFER,NOTIFY From:"SUPPORTSTREEMVM";tag=8r760236X6D8j To:;tag=5EE13246313536410000155C Call-ID:f0c42408-6f4b-1236-cf98-dd1cb2d72f2a CSeq:117383574 INVITE Server:PBX-IP Media Gateway/2.1 Via:SIP/2.0/UDP 10.10.101.238:5080;rport;branch=z9hG4bK87c7NZZ5Btm3c Content-Length:0 ------------------------------------------------------------------------ ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3023 tport_deliver() tport(000000DB8F2E4C10): msg 000000DB8E2671E0 (451 bytes) from udp/10.10.5.14:5080/sip next=0000000000000000 ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:3299 agent_recv_response() nta: received 180 Ringing for INVITE (117383574) ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:3366 agent_recv_response() nta: 180 Ringing is going to a transaction ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:4222 tport_release() tport(000000DB8F2E4C10): 000000DB8F5A0CB0 by 000000DB8F3A6630 with 000000DB8E2671E0 (preliminary) ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(000000DB8F7B7200): event r_invite 180 Ringing ..\..\sofia-sip\libsofia-sip-ua\nua\nua_session.c:4139 signal_call_state_change() nua(000000DB8F7B7200): call state changed: proceeding -> proceeding ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(000000DB8F7B7200): event i_state 180 Ringing ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2749 tport_wakeup_pri() tport_wakeup_pri(000000DB8F2E4C10): events IN ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2864 tport_recv_event() tport_recv_event(000000DB8F2E4C10) ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3205 tport_recv_iovec() tport(000000DB8F2E4C10) msg 000000DB8E2675A0 from (udp/10.10.101.238:5080) has 747 bytes, veclen = 1 recv 747 bytes from udp/[10.10.5.14]:1028 at 19:21:23.159213: ------------------------------------------------------------------------ SIP/2.0 200 OK Content-Type:application/sdp Supported:replaces,100rel Contact: Reason:E.182;text="Fax" Allow:INVITE,ACK,OPTIONS,BYE,CANCEL,REGISTER,INFO,PRACK,REFER,NOTIFY From:"SUPPORTSTREEMVM";tag=8r760236X6D8j To:;tag=5EE13246313536410000155C Call-ID:f0c42408-6f4b-1236-cf98-dd1cb2d72f2a CSeq:117383574 INVITE Server:PBX-IP Media Gateway/2.1 Via:SIP/2.0/UDP 10.10.101.238:5080;rport;branch=z9hG4bK87c7NZZ5Btm3c Content-Length:217 v=0 o=phone 21830 8286 IN IP4 10.10.5.14 s=- c=IN IP4 10.10.5.14 t=0 0 m=audio 49012 RTP/AVP 0 101 13 a=rtpmap:0 PCMU/8000/1 a=ptime:20 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=rtpmap:13 CN/8000 ------------------------------------------------------------------------ ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3023 tport_deliver() tport(000000DB8F2E4C10): msg 000000DB8E2675A0 (747 bytes) from udp/10.10.5.14:5080/sip next=0000000000000000 ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:3299 agent_recv_response() nta: received 200 OK for INVITE (117383574) ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:3366 agent_recv_response() nta: 200 OK is going to a transaction ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:4222 tport_release() tport(000000DB8F2E4C10): 000000DB8F5A0CB0 by 000000DB8F3A6630 with 000000DB8E2675A0 ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1171 soa_set_remote_sdp() soa_set_remote_sdp(static::000000DB8F9933D0, 0000000000000000, 000000DB90B3D222, 217) called ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1595 soa_process_answer() soa_process_answer(static::000000DB8F9933D0) called ..\..\sofia-sip\libsofia-sip-ua\soa\soa_static.c:1148 offer_answer_step() soa_static_offer_answer_action(000000DB8F9933D0, soa_process_answer): called ..\..\sofia-sip\libsofia-sip-ua\soa\soa_static.c:1029 soa_sdp_mode_set() soa_sdp_mode_set(000000DB8D9D1750, 000000DB8D9C6020, ""): called ..\..\sofia-sip\libsofia-sip-ua\soa\soa_static.c:1304 offer_answer_step() soa_static(000000DB8F9933D0, soa_process_answer): upgrade codecs with remote description ..\..\sofia-sip\libsofia-sip-ua\soa\soa_static.c:1446 offer_answer_step() soa_static(000000DB8F9933D0, soa_process_answer): storing local description ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1730 soa_activate() soa_activate(static::000000DB8F9933D0, (nil)) called ..\..\sofia-sip\libsofia-sip-ua\nua\nua_session.c:988 nua_session_client_response() nua(000000DB8F7B7200): INVITE: processed SDP answer in 200 OK ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(000000DB8F7B7200): event r_invite 200 OK ..\..\sofia-sip\libsofia-sip-ua\nua\nua_session.c:4139 signal_call_state_change() nua(000000DB8F7B7200): call state changed: proceeding -> completing, received answer ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1098 soa_get_remote_sdp() soa_get_remote_sdp(static::000000DB8F9933D0, [000000DB9064F3B8], [000000DB9064F3A0], [0000000000000000]) called ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:616 soa_get_params() soa_get_params(static::000000DB8F9933D0, ...) called ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(000000DB8F7B7200): event i_state 200 OK ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:529 nua_signal() nua(000000DB8F7B7200): sent signal r_ack ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:569 nua_stack_signal() nua(000000DB8F7B7200): recv signal r_ack ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:403 soa_set_params() soa_set_params(static::000000DB8F9933D0, ...) called ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1730 soa_activate() soa_activate(static::000000DB8F9933D0, (nil)) called ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:2665 nta_tpn_by_url() nta: selecting scheme sip ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3257 tport_tsend() tport_tsend(000000DB8F2E4C10) tpn = */10.10.5.14:5060 ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:4046 tport_resolve() tport_resolve addrinfo = 10.10.5.14:5060 ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:4680 tport_by_addrinfo() tport(000000DB8F2E4C10): not found by name */10.10.5.14:5060 ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3594 tport_vsend() tport_vsend(000000DB8F2E4C10): 428 bytes of 428 to udp/10.10.5.14:5060 ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3492 tport_send_msg() tport_vsend returned 428 send 428 bytes to udp/[10.10.5.14]:5060 at 19:21:23.165212: ------------------------------------------------------------------------ ACK sip:18156548285@10.10.5.14:5060 SIP/2.0 Via: SIP/2.0/UDP 10.10.101.238:5080;rport;branch=z9hG4bK9g6ZQtg982apr Max-Forwards: 70 From: "SUPPORTSTREEMVM" ;tag=8r760236X6D8j To: ;tag=5EE13246313536410000155C Call-ID: f0c42408-6f4b-1236-cf98-dd1cb2d72f2a CSeq: 117383574 ACK Contact: Content-Length: 0 ------------------------------------------------------------------------ ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:8304 outgoing_send() nta: sent ACK (117383574) to */10.10.5.14:5060 ..\..\sofia-sip\libsofia-sip-ua\nua\nua_session.c:4139 signal_call_state_change() nua(000000DB8F7B7200): call state changed: completing -> ready ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(000000DB8F7B7200): event i_state 200 ACK sent ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(000000DB8F7B7200): event i_active 200 Call active fb05e962-059f-4bb4-b9e1-54130ed0b2e3 2018-01-08 13:21:23.159213 [NOTICE] sofia.c:8218 Channel [sofia/external/18156548285] has been answered fb05e962-059f-4bb4-b9e1-54130ed0b2e3 2018-01-08 13:21:23.179211 [INFO] switch_channel.c:3129 sofia/external/18156548285 Flipping CID from "SUPPORTSTREEMVM" <8887344585> to "Outbound Call" <18156548285> ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2749 tport_wakeup_pri() tport_wakeup_pri(000000DB8F2E4C10): events IN ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2864 tport_recv_event() tport_recv_event(000000DB8F2E4C10) ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3205 tport_recv_iovec() tport(000000DB8F2E4C10) msg 000000DB8E266A60 from (udp/10.10.101.238:5080) has 865 bytes, veclen = 1 recv 865 bytes from udp/[10.10.5.14]:1028 at 19:21:23.186209: ------------------------------------------------------------------------ INVITE sip:gw+10.10.5.14@10.10.101.238:5080;transport=udp;gw=10.10.5.14 SIP/2.0 Content-Type:application/sdp Allow:INVITE,BYE,CANCEL,REFER,NOTIFY,OPTIONS,REGISTER,INFO,ACK,PRACK Supported:100rel,replaces Expires:120 To:"SUPPORTSTREEMVM";tag=8r760236X6D8j From:;tag=5EE13246313536410000155C Call-ID:f0c42408-6f4b-1236-cf98-dd1cb2d72f2a CSeq:1 INVITE Max-Forwards:70 User-Agent:PBX-IP Media Gateway Contact: Via:SIP/2.0/UDP 10.10.5.14:5060;branch=z9hG4bK12A5F71F4F26DE87885611256A0FA027 Content-Length:263 v=0 o=phone 21830 8287 IN IP4 10.10.5.14 s=- c=IN IP4 10.10.5.14 t=0 0 m=image 49012 udptl t38 a=T38FaxMaxBuffer:1800 a=T38FaxMaxDatagram:150 a=T38FaxVersion:0 a=T38MaxBitRate:14400 a=T38FaxRateManagement:transferredTCF a=T38FaxUdpEC:t38UDPRedundancy ------------------------------------------------------------------------ ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3023 tport_deliver() tport(000000DB8F2E4C10): msg 000000DB8E266A60 (865 bytes) from udp/10.10.5.14:5080/sip next=0000000000000000 ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:2880 agent_recv_request() nta: received INVITE sip:gw+10.10.5.14@10.10.101.238:5080;transport=udp;gw=10.10.5.14 SIP/2.0 (CSeq 1) ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:3248 agent_aliases() nta: canonizing sip:gw+10.10.5.14@10.10.101.238:5080 with contact ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:3060 agent_recv_request() nta: INVITE (1) going to existing leg ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:1348 set_timeout() nta: timer shortened to 200 ms ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1302 soa_init_offer_answer() soa_init_offer_answer(static::000000DB8F9933D0) called ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1171 soa_set_remote_sdp() soa_set_remote_sdp(static::000000DB8F9933D0, 0000000000000000, 000000DB8E4E653A, 263) called ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3257 tport_tsend() tport_tsend(000000DB8F2E4C10) tpn = UDP/10.10.5.14:5060 ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:4046 tport_resolve() tport_resolve addrinfo = 10.10.5.14:5060 ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:4680 tport_by_addrinfo() tport(000000DB8F2E4C10): not found by name UDP/10.10.5.14:5060 ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3594 tport_vsend() tport_vsend(000000DB8F2E4C10): 386 bytes of 386 to udp/10.10.5.14:5060 ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3492 tport_send_msg() tport_vsend returned 386 send 386 bytes to udp/[10.10.5.14]:5060 at 19:21:23.186209: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via:SIP/2.0/UDP 10.10.5.14:5060;branch=z9hG4bK12A5F71F4F26DE87885611256A0FA027 From:;tag=5EE13246313536410000155C To:"SUPPORTSTREEMVM";tag=8r760236X6D8j Call-ID:f0c42408-6f4b-1236-cf98-dd1cb2d72f2a CSeq:1 INVITE User-Agent: FreeSWITCH-mod_sofia/1.6.19+git~20171109T180403Z~27aebc7253~64bit Content-Length: 0 ------------------------------------------------------------------------ ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:6791 incoming_reply() nta: sent 100 Trying for INVITE (1) ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(000000DB8F7B7200): event i_invite 100 Trying ..\..\sofia-sip\libsofia-sip-ua\nua\nua_session.c:4145 signal_call_state_change() nua(000000DB8F7B7200): ready call updated: received received offer ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1098 soa_get_remote_sdp() soa_get_remote_sdp(static::000000DB8F9933D0, [000000DB9064F288], [000000DB9064F270], [0000000000000000]) called ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(000000DB8F7B7200): event i_state 100 Trying ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:529 nua_signal() nua(000000DB8F7B7200): sent signal r_respond ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:573 nua_stack_signal() nua(000000DB8F7B7200): recv signal r_respond 488 Not Acceptable Here ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:403 soa_set_params() soa_set_params(static::000000DB8F9933D0, ...) called ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1214 soa_clear_remote_sdp() soa_clear_remote_sdp(static::000000DB8F9933D0) called ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3257 tport_tsend() tport_tsend(000000DB8F2E4C10) tpn = UDP/10.10.5.14:5060 ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:4046 tport_resolve() tport_resolve addrinfo = 10.10.5.14:5060 ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:4680 tport_by_addrinfo() tport(000000DB8F2E4C10): not found by name UDP/10.10.5.14:5060 ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3594 tport_vsend() tport_vsend(000000DB8F2E4C10): 548 bytes of 548 to udp/10.10.5.14:5060 ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3492 tport_send_msg() tport_vsend returned 548 send 548 bytes to udp/[10.10.5.14]:5060 at 19:21:23.199207: ------------------------------------------------------------------------ SIP/2.0 488 Not Acceptable Here Via:SIP/2.0/UDP 10.10.5.14:5060;branch=z9hG4bK12A5F71F4F26DE87885611256A0FA027 From:;tag=5EE13246313536410000155C To:"SUPPORTSTREEMVM";tag=8r760236X6D8j Call-ID:f0c42408-6f4b-1236-cf98-dd1cb2d72f2a CSeq:1 INVITE User-Agent: FreeSWITCH-mod_sofia/1.6.19+git~20171109T180403Z~27aebc7253~64bit Accept: application/sdp Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY Supported: timer, path, replaces Content-Length: 0 ------------------------------------------------------------------------ ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:6791 incoming_reply() nta: sent 488 Not Acceptable Here for INVITE (1) ..\..\sofia-sip\libsofia-sip-ua\nua\nua_session.c:4145 signal_call_state_change() nua(000000DB8F7B7200): ready call updated: init ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(000000DB8F7B7200): event i_state 488 Not Acceptable Here ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(000000DB8F7B7200): event i_active 488 Call active ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1302 soa_init_offer_answer() soa_init_offer_answer(static::000000DB8F9933D0) called ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2749 tport_wakeup_pri() tport_wakeup_pri(000000DB8F2E4C10): events IN ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2864 tport_recv_event() tport_recv_event(000000DB8F2E4C10) ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3205 tport_recv_iovec() tport(000000DB8F2E4C10) msg 000000DB8E2662E0 from (udp/10.10.101.238:5080) has 411 bytes, veclen = 1 recv 411 bytes from udp/[10.10.5.14]:1028 at 19:21:23.219210: ------------------------------------------------------------------------ ACK sip:gw+10.10.5.14@10.10.101.238:5080;transport=udp;gw=10.10.5.14 SIP/2.0 Call-ID:f0c42408-6f4b-1236-cf98-dd1cb2d72f2a CSeq:1 ACK From:;tag=5EE13246313536410000155C To:"SUPPORTSTREEMVM";tag=8r760236X6D8j Via:SIP/2.0/UDP 10.10.5.14:5060;branch=z9hG4bK12A5F71F4F26DE87885611256A0FA027 Max-Forwards:70 User-Agent:PBX-IP Media Gateway Content-Length:0 ------------------------------------------------------------------------ ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3023 tport_deliver() tport(000000DB8F2E4C10): msg 000000DB8E2662E0 (411 bytes) from udp/10.10.5.14:5080/sip next=0000000000000000 ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:2880 agent_recv_request() nta: received ACK sip:gw+10.10.5.14@10.10.101.238:5080;transport=udp;gw=10.10.5.14 SIP/2.0 (CSeq 1) ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:3019 agent_recv_request() nta: ACK (1) is going to INVITE (1) ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2749 tport_wakeup_pri() tport_wakeup_pri(000000DB8F2E4C10): events IN ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2864 tport_recv_event() tport_recv_event(000000DB8F2E4C10) ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3205 tport_recv_iovec() tport(000000DB8F2E4C10) msg 000000DB8E2662E0 from (udp/10.10.101.238:5080) has 440 bytes, veclen = 1 recv 440 bytes from udp/[10.10.5.14]:1028 at 19:21:23.225246: ------------------------------------------------------------------------ BYE sip:gw+10.10.5.14@10.10.101.238:5080;transport=udp;gw=10.10.5.14 SIP/2.0 Reason:E.182;text="Unknown" To:"SUPPORTSTREEMVM";tag=8r760236X6D8j From:;tag=5EE13246313536410000155C Call-ID:f0c42408-6f4b-1236-cf98-dd1cb2d72f2a CSeq:2 BYE Max-Forwards:70 User-Agent:PBX-IP Media Gateway Via:SIP/2.0/UDP 10.10.5.14:5060;branch=z9hG4bK0AFCAE9B17E7D9127AE53C4E8A560C4D Content-Length:0 ------------------------------------------------------------------------ ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3023 tport_deliver() tport(000000DB8F2E4C10): msg 000000DB8E2662E0 (440 bytes) from udp/10.10.5.14:5080/sip next=0000000000000000 ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:2880 agent_recv_request() nta: received BYE sip:gw+10.10.5.14@10.10.101.238:5080;transport=udp;gw=10.10.5.14 SIP/2.0 (CSeq 2) ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:3248 agent_aliases() nta: canonizing sip:gw+10.10.5.14@10.10.101.238:5080 with contact ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:3060 agent_recv_request() nta: BYE (2) going to existing leg ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(000000DB8F7B7200): event i_bye 100 Trying fb05e962-059f-4bb4-b9e1-54130ed0b2e3 2018-01-08 13:21:23.239246 [NOTICE] sofia.c:1012 Hangup sofia/external/18156548285 [CS_EXECUTE] [NORMAL_CLEARING] ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:529 nua_signal() nua(000000DB8F7B7200): sent signal r_respond ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:529 nua_signal() nua(000000DB8F7B7200): sent signal r_destroy ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:573 nua_stack_signal() nua(000000DB8F7B7200): recv signal r_respond 200 OK ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:403 soa_set_params() soa_set_params(static::000000DB8F9933D0, ...) called ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3257 tport_tsend() tport_tsend(000000DB8F2E4C10) tpn = UDP/10.10.5.14:5060 ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:4046 tport_resolve() tport_resolve addrinfo = 10.10.5.14:5060 ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:4680 tport_by_addrinfo() tport(000000DB8F2E4C10): not found by name UDP/10.10.5.14:5060 ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3594 tport_vsend() tport_vsend(000000DB8F2E4C10): 503 bytes of 503 to udp/10.10.5.14:5060 ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3492 tport_send_msg() tport_vsend returned 503 send 503 bytes to udp/[10.10.5.14]:5060 at 19:21:23.240214: ------------------------------------------------------------------------ SIP/2.0 200 OK Via:SIP/2.0/UDP 10.10.5.14:5060;branch=z9hG4bK0AFCAE9B17E7D9127AE53C4E8A560C4D From:;tag=5EE13246313536410000155C To:"SUPPORTSTREEMVM";tag=8r760236X6D8j Call-ID:f0c42408-6f4b-1236-cf98-dd1cb2d72f2a CSeq:2 BYE User-Agent: FreeSWITCH-mod_sofia/1.6.19+git~20171109T180403Z~27aebc7253~64bit Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY Supported: timer, path, replaces Content-Length: 0 ------------------------------------------------------------------------ ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:6791 incoming_reply() nta: sent 200 OK for BYE (2) ..\..\sofia-sip\libsofia-sip-ua\nua\nua_dialog.c:397 nua_dialog_usage_remove_at() nua(000000DB8F7B7200): removing session usage ..\..\sofia-sip\libsofia-sip-ua\nua\nua_session.c:4139 signal_call_state_change() nua(000000DB8F7B7200): call state changed: ready -> terminated ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(000000DB8F7B7200): event i_state 200 Session Terminated ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(000000DB8F7B7200): event i_terminated 200 Session Terminated ..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:356 soa_destroy() soa_destroy(static::000000DB8F9933D0) called ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:4470 nta_leg_destroy() nta_leg_destroy(000000DB90D6DBC0) ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:569 nua_stack_signal() nua(000000DB8F7B7200): recv signal r_destroy ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:4470 nta_leg_destroy() nta_leg_destroy(0000000000000000) fb05e962-059f-4bb4-b9e1-54130ed0b2e3 2018-01-08 13:21:23.239246 [NOTICE] switch_core_session.c:1683 Session 21 (sofia/external/18156548285) Ended fb05e962-059f-4bb4-b9e1-54130ed0b2e3 2018-01-08 13:21:23.239246 [NOTICE] switch_core_session.c:1687 Close Channel sofia/external/18156548285 [CS_DESTROY] ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:1296 agent_timer() nta: timer set next to 106 ms ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:7159 _nta_incoming_timer() nta: timer J fired, terminate 200 response ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:5825 incoming_reclaim_queued() incoming_reclaim_all(0000000000000000, 0000000000000000, 000000DB9064FA40) ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:7188 _nta_incoming_timer() nta_incoming_timer: 0/0 resent, 0/0 tout, 1/3 term, 1/3 free ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:1296 agent_timer() nta: timer set next to 4727 ms ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:7134 _nta_incoming_timer() nta: timer I fired, terminate 488 response ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:5825 incoming_reclaim_queued() incoming_reclaim_all(0000000000000000, 0000000000000000, 000000DB9064FA40) ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:7188 _nta_incoming_timer() nta_incoming_timer: 0/0 resent, 0/0 tout, 1/2 term, 1/2 free ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:1296 agent_timer() nta: timer set next to 22960 ms ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:9101 outgoing_timer_dk() nta: timer D fired, terminate INVITE (117383574) ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:8799 outgoing_reclaim_queued() outgoing_reclaim_all(0000000000000000, 0000000000000000, 000000DB9064FA40) ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:8929 _nta_outgoing_timer() nta_outgoing_timer: 0/0 resent, 0/1 tout, 1/2 term, 1/3 free ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:1296 agent_timer() nta: timer set next to 3979 ms ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:9101 outgoing_timer_dk() nta: timer D fired, terminate INVITE (117383574) ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:8799 outgoing_reclaim_queued() outgoing_reclaim_all(0000000000000000, 0000000000000000, 000000DB9064FA40) ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:8929 _nta_outgoing_timer() nta_outgoing_timer: 0/0 resent, 0/1 tout, 1/1 term, 1/2 free ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:1296 agent_timer() nta: timer set next to 6 ms ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:8982 outgoing_timer_bf() nta: timer F fired, terminating ACK (117383574) ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:8799 outgoing_reclaim_queued() outgoing_reclaim_all(0000000000000000, 0000000000000000, 000000DB9064FA40) ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:8929 _nta_outgoing_timer() nta_outgoing_timer: 0/0 resent, 1/1 tout, 0/0 term, 1/1 free ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:1296 agent_timer() nta: timer set next to 74 ms ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:7159 _nta_incoming_timer() nta: timer J fired, terminate 200 response ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:5825 incoming_reclaim_queued() incoming_reclaim_all(0000000000000000, 0000000000000000, 000000DB9064FA40) ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:7188 _nta_incoming_timer() nta_incoming_timer: 0/0 resent, 0/0 tout, 1/1 term, 1/1 free ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:1289 agent_timer() nta: timer not set