freeswitch@fusionpbx3> sofia global siptrace on +OK Global siptrace on recv 1230 bytes from udp/[192.168.111.93]:5060 at 11:13:34.328465: ------------------------------------------------------------------------ INVITE sip:0711519845@pbx.fusionrnd.jennyme.co.za;user=phone SIP/2.0 Via: SIP/2.0/UDP 192.168.111.93:5060;branch=z9hG4bK-c2l51vaxyel5;rport From: "202" ;tag=g5h39y6sdl To: Call-ID: 313439333731363431333433353836-447ksou2mtx8 CSeq: 1 INVITE Max-Forwards: 70 User-Agent: snom821/8.7.5.35 Contact: ;reg-id=1 X-Serialnumber: 000413489135 P-Key-Flags: resolution="31x13", keys="4" Accept: application/sdp Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO, UPDATE Allow-Events: talk, hold, refer, call-info Supported: timer, 100rel, replaces, from-change Session-Expires: 3600 Min-SE: 90 Content-Type: application/sdp Content-Length: 402 v=0 o=root 1769816236 1769816236 IN IP4 192.168.111.93 s=call c=IN IP4 192.168.111.93 t=0 0 m=audio 56744 RTP/AVP 18 8 0 99 112 9 101 a=direction:both a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:99 G726-32/8000 a=rtpmap:112 AAL2-G726-32/8000 a=rtpmap:9 G722/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 a=sendrecv ------------------------------------------------------------------------ send 339 bytes to udp/[192.168.111.93]:5060 at 11:13:34.329076: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.111.93:5060;branch=z9hG4bK-c2l51vaxyel5;rport=5060 From: "202" ;tag=g5h39y6sdl To: Call-ID: 313439333731363431333433353836-447ksou2mtx8 CSeq: 1 INVITE User-Agent: FreeSWITCH Content-Length: 0 ------------------------------------------------------------------------ 2017-05-02 11:13:34.320561 [NOTICE] switch_channel.c:1104 New Channel sofia/internal172/202@pbx.fusionrnd.jennyme.co.za [d19b1490-69a5-4a4d-b701-e04861b01e5e] 2017-05-02 11:13:34.320561 [DEBUG] switch_core_state_machine.c:584 (sofia/internal172/202@pbx.fusionrnd.jennyme.co.za) Running State Change CS_NEW (Cur 1 Tot 2) 2017-05-02 11:13:34.320561 [DEBUG] sofia.c:9834 sofia/internal172/202@pbx.fusionrnd.jennyme.co.za receiving invite from 192.168.111.93:5060 version: 1.6.17 -34-0fc0946 64bit 2017-05-02 11:13:34.320561 [DEBUG] sofia.c:9950 IP 192.168.111.93 Approved by acl "domains[]". Access Granted. 2017-05-02 11:13:34.320561 [DEBUG] sofia.c:7048 Channel sofia/internal172/202@pbx.fusionrnd.jennyme.co.za entering state [received][100] 2017-05-02 11:13:34.320561 [DEBUG] sofia.c:7058 Remote SDP: v=0 o=root 1769816236 1769816236 IN IP4 192.168.111.93 s=call c=IN IP4 192.168.111.93 t=0 0 m=audio 56744 RTP/AVP 18 8 0 99 112 9 101 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:99 G726-32/8000 a=rtpmap:112 AAL2-G726-32/8000 a=rtpmap:9 G722/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=direction:both a=ptime:20 2017-05-02 11:13:34.320561 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [G729:18:8000:20:8000:1]/[G729:18:8000:20:8000:1] 2017-05-02 11:13:34.320561 [DEBUG] switch_core_media.c:4491 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match 2017-05-02 11:13:34.320561 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [G729:18:8000:20:8000:1]/[G722:9:8000:20:64000:1] 2017-05-02 11:13:34.320561 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1] 2017-05-02 11:13:34.320561 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1] 2017-05-02 11:13:34.320561 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [G729:18:8000:20:8000:1]/[GSM:3:8000:20:13200:1] 2017-05-02 11:13:34.320561 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1] 2017-05-02 11:13:34.320561 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1] 2017-05-02 11:13:34.320561 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2017-05-02 11:13:34.320561 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2017-05-02 11:13:34.320561 [DEBUG] switch_core_media.c:4491 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match 2017-05-02 11:13:34.320561 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[GSM:3:8000:20:13200:1] 2017-05-02 11:13:34.320561 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1] 2017-05-02 11:13:34.320561 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1] 2017-05-02 11:13:34.320561 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2017-05-02 11:13:34.320561 [DEBUG] switch_core_media.c:4491 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match 2017-05-02 11:13:34.320561 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2017-05-02 11:13:34.320561 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[GSM:3:8000:20:13200:1] 2017-05-02 11:13:34.320561 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [G726-32:99:8000:20:0:1]/[G729:18:8000:20:8000:1] 2017-05-02 11:13:34.320561 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [G726-32:99:8000:20:0:1]/[G722:9:8000:20:64000:1] 2017-05-02 11:13:34.320561 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [G726-32:99:8000:20:0:1]/[PCMU:0:8000:20:64000:1] 2017-05-02 11:13:34.320561 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [G726-32:99:8000:20:0:1]/[PCMA:8:8000:20:64000:1] 2017-05-02 11:13:34.320561 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [G726-32:99:8000:20:0:1]/[GSM:3:8000:20:13200:1] 2017-05-02 11:13:34.320561 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [AAL2-G726-32:112:8000:20:0:1]/[G729:18:8000:20:8000:1] 2017-05-02 11:13:34.320561 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [AAL2-G726-32:112:8000:20:0:1]/[G722:9:8000:20:64000:1] 2017-05-02 11:13:34.320561 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [AAL2-G726-32:112:8000:20:0:1]/[PCMU:0:8000:20:64000:1] 2017-05-02 11:13:34.320561 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [AAL2-G726-32:112:8000:20:0:1]/[PCMA:8:8000:20:64000:1] 2017-05-02 11:13:34.320561 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [AAL2-G726-32:112:8000:20:0:1]/[GSM:3:8000:20:13200:1] 2017-05-02 11:13:34.320561 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [G722:9:8000:20:64000:1]/[G729:18:8000:20:8000:1] 2017-05-02 11:13:34.320561 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1] 2017-05-02 11:13:34.320561 [DEBUG] switch_core_media.c:4491 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match 2017-05-02 11:13:34.320561 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2017-05-02 11:13:34.320561 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2017-05-02 11:13:34.320561 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [G722:9:8000:20:64000:1]/[GSM:3:8000:20:13200:1] 2017-05-02 11:13:34.320561 [DEBUG] switch_core_media.c:4352 Set telephone-event payload to 101@8000 2017-05-02 11:13:34.320561 [DEBUG] switch_core_media.c:3056 Set Codec sofia/internal172/202@pbx.fusionrnd.jennyme.co.za G729/8000 20 ms 160 samples 8000 bits 1 channels 2017-05-02 11:13:34.320561 [DEBUG] switch_core_codec.c:111 sofia/internal172/202@pbx.fusionrnd.jennyme.co.za Original read codec set to G729:18 2017-05-02 11:13:34.320561 [DEBUG] switch_core_media.c:4695 Set telephone-event payload to 101@8000 2017-05-02 11:13:34.320561 [DEBUG] switch_core_media.c:4754 sofia/internal172/202@pbx.fusionrnd.jennyme.co.za Set 2833 dtmf send payload to 101 recv payload to 101 2017-05-02 11:13:34.320561 [DEBUG] sofia.c:7471 (sofia/internal172/202@pbx.fusionrnd.jennyme.co.za) State Change CS_NEW -> CS_INIT 2017-05-02 11:13:34.320561 [DEBUG] switch_core_state_machine.c:603 (sofia/internal172/202@pbx.fusionrnd.jennyme.co.za) State NEW 2017-05-02 11:13:34.320561 [DEBUG] switch_core_state_machine.c:584 (sofia/internal172/202@pbx.fusionrnd.jennyme.co.za) Running State Change CS_INIT (Cur 1 Tot 2) 2017-05-02 11:13:34.320561 [DEBUG] switch_core_state_machine.c:627 (sofia/internal172/202@pbx.fusionrnd.jennyme.co.za) State INIT 2017-05-02 11:13:34.320561 [DEBUG] mod_sofia.c:90 sofia/internal172/202@pbx.fusionrnd.jennyme.co.za SOFIA INIT 2017-05-02 11:13:34.320561 [DEBUG] switch_core_state_machine.c:40 sofia/internal172/202@pbx.fusionrnd.jennyme.co.za Standard INIT 2017-05-02 11:13:34.320561 [DEBUG] switch_core_state_machine.c:48 (sofia/internal172/202@pbx.fusionrnd.jennyme.co.za) State Change CS_INIT -> CS_ROUTING 2017-05-02 11:13:34.320561 [DEBUG] switch_core_state_machine.c:627 (sofia/internal172/202@pbx.fusionrnd.jennyme.co.za) State INIT going to sleep 2017-05-02 11:13:34.320561 [DEBUG] switch_core_state_machine.c:584 (sofia/internal172/202@pbx.fusionrnd.jennyme.co.za) Running State Change CS_ROUTING (Cur 1 Tot 2) 2017-05-02 11:13:34.320561 [DEBUG] switch_channel.c:2249 (sofia/internal172/202@pbx.fusionrnd.jennyme.co.za) Callstate Change DOWN -> RINGING 2017-05-02 11:13:34.320561 [DEBUG] switch_core_state_machine.c:643 (sofia/internal172/202@pbx.fusionrnd.jennyme.co.za) State ROUTING 2017-05-02 11:13:34.320561 [DEBUG] mod_sofia.c:143 sofia/internal172/202@pbx.fusionrnd.jennyme.co.za SOFIA ROUTING 2017-05-02 11:13:34.320561 [DEBUG] switch_core_state_machine.c:236 sofia/internal172/202@pbx.fusionrnd.jennyme.co.za Standard ROUTING 2017-05-02 11:13:34.320561 [INFO] mod_dialplan_xml.c:637 Processing 202 <202>->0711519845 in context public Dialplan: sofia/internal172/202@pbx.fusionrnd.jennyme.co.za parsing [public->27182930078] continue=false Dialplan: sofia/internal172/202@pbx.fusionrnd.jennyme.co.za Regex (FAIL) [27182930078] destination_number(0711519845) =~ /^(5549je[2-9][2-9]yv)$/ break=on-false Dialplan: sofia/internal172/202@pbx.fusionrnd.jennyme.co.za parsing [public->27182932838] continue=false Dialplan: sofia/internal172/202@pbx.fusionrnd.jennyme.co.za Regex (FAIL) [27182932838] destination_number(0711519845) =~ /^(5575je[2-9][2-9]yv)$/ break=on-false Dialplan: sofia/internal172/202@pbx.fusionrnd.jennyme.co.za parsing [public->27182943949] continue=false Dialplan: sofia/internal172/202@pbx.fusionrnd.jennyme.co.za Regex (FAIL) [27182943949] destination_number(0711519845) =~ /^(5576je[2-9][2-9]yv)$/ break=on-false Dialplan: sofia/internal172/202@pbx.fusionrnd.jennyme.co.za parsing [public->5467jennyv] continue=false Dialplan: sofia/internal172/202@pbx.fusionrnd.jennyme.co.za Regex (FAIL) [5467jennyv] destination_number(0711519845) =~ /5467jennyv/ break=on-false 2017-05-02 11:13:34.340534 [INFO] switch_core_state_machine.c:311 No Route, Aborting 2017-05-02 11:13:34.340534 [NOTICE] switch_core_state_machine.c:312 Hangup sofia/internal172/202@pbx.fusionrnd.jennyme.co.za [CS_ROUTING] [NO_ROUTE_DESTINATION] 2017-05-02 11:13:34.340534 [DEBUG] switch_core_state_machine.c:643 (sofia/internal172/202@pbx.fusionrnd.jennyme.co.za) State ROUTING going to sleep 2017-05-02 11:13:34.340534 [DEBUG] switch_core_state_machine.c:584 (sofia/internal172/202@pbx.fusionrnd.jennyme.co.za) Running State Change CS_HANGUP (Cur 1 Tot 2) 2017-05-02 11:13:34.340534 [DEBUG] switch_core_state_machine.c:850 (sofia/internal172/202@pbx.fusionrnd.jennyme.co.za) Callstate Change RINGING -> HANGUP 2017-05-02 11:13:34.340534 [DEBUG] switch_core_state_machine.c:852 (sofia/internal172/202@pbx.fusionrnd.jennyme.co.za) State HANGUP 2017-05-02 11:13:34.340534 [DEBUG] mod_sofia.c:438 Channel sofia/internal172/202@pbx.fusionrnd.jennyme.co.za hanging up, cause: NO_ROUTE_DESTINATION 2017-05-02 11:13:34.340534 [DEBUG] mod_sofia.c:577 Responding to INVITE with: 404 send 881 bytes to udp/[192.168.111.93]:5060 at 11:13:34.360139: ------------------------------------------------------------------------ SIP/2.0 404 Not Found Via: SIP/2.0/UDP 192.168.111.93:5060;branch=z9hG4bK-c2l51vaxyel5;rport=5060 Max-Forwards: 70 From: "202" ;tag=g5h39y6sdl To: ;tag=y4440N1DS262e Call-ID: 313439333731363431333433353836-447ksou2mtx8 CSeq: 1 INVITE User-Agent: FreeSWITCH Accept: application/sdp Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, path, replaces Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer Reason: Q.850;cause=3;text="NO_ROUTE_DESTINATION" Content-Length: 0 Remote-Party-ID: "0711519845" ;party=calling;privacy=off;screen=no ------------------------------------------------------------------------ 2017-05-02 11:13:34.340534 [DEBUG] switch_core_state_machine.c:60 sofia/internal172/202@pbx.fusionrnd.jennyme.co.za Standard HANGUP, cause: NO_ROUTE_DESTINATION 2017-05-02 11:13:34.340534 [DEBUG] switch_core_state_machine.c:852 (sofia/internal172/202@pbx.fusionrnd.jennyme.co.za) State HANGUP going to sleep 2017-05-02 11:13:34.340534 [DEBUG] switch_core_state_machine.c:619 (sofia/internal172/202@pbx.fusionrnd.jennyme.co.za) State Change CS_HANGUP -> CS_REPORTING 2017-05-02 11:13:34.360531 [DEBUG] switch_core_state_machine.c:584 (sofia/internal172/202@pbx.fusionrnd.jennyme.co.za) Running State Change CS_REPORTING (Cur 1 Tot 2) 2017-05-02 11:13:34.360531 [DEBUG] switch_core_state_machine.c:938 (sofia/internal172/202@pbx.fusionrnd.jennyme.co.za) State REPORTING recv 483 bytes from udp/[192.168.111.93]:5060 at 11:13:34.401456: ------------------------------------------------------------------------ ACK sip:0711519845@pbx.fusionrnd.jennyme.co.za;user=phone SIP/2.0 Via: SIP/2.0/UDP 192.168.111.93:5060;branch=z9hG4bK-c2l51vaxyel5;rport From: "202" ;tag=g5h39y6sdl To: ;tag=y4440N1DS262e Call-ID: 313439333731363431333433353836-447ksou2mtx8 CSeq: 1 ACK Max-Forwards: 70 User-Agent: snom821/8.7.5.35 Contact: ;reg-id=1 Content-Length: 0 ------------------------------------------------------------------------ 2017-05-02 11:13:34.620524 [DEBUG] switch_core_state_machine.c:174 sofia/internal172/202@pbx.fusionrnd.jennyme.co.za Standard REPORTING, cause: NO_ROUTE_DESTINATION 2017-05-02 11:13:34.620524 [DEBUG] switch_core_state_machine.c:938 (sofia/internal172/202@pbx.fusionrnd.jennyme.co.za) State REPORTING going to sleep 2017-05-02 11:13:34.620524 [DEBUG] switch_core_state_machine.c:610 (sofia/internal172/202@pbx.fusionrnd.jennyme.co.za) State Change CS_REPORTING -> CS_DESTROY 2017-05-02 11:13:34.620524 [DEBUG] switch_core_session.c:1664 Session 2 (sofia/internal172/202@pbx.fusionrnd.jennyme.co.za) Locked, Waiting on external entities 2017-05-02 11:13:34.620524 [NOTICE] switch_core_session.c:1682 Session 2 (sofia/internal172/202@pbx.fusionrnd.jennyme.co.za) Ended 2017-05-02 11:13:34.620524 [NOTICE] switch_core_session.c:1686 Close Channel sofia/internal172/202@pbx.fusionrnd.jennyme.co.za [CS_DESTROY] 2017-05-02 11:13:34.620524 [DEBUG] switch_core_state_machine.c:741 (sofia/internal172/202@pbx.fusionrnd.jennyme.co.za) Running State Change CS_DESTROY (Cur 0 Tot 2) 2017-05-02 11:13:34.620524 [DEBUG] switch_core_state_machine.c:751 (sofia/internal172/202@pbx.fusionrnd.jennyme.co.za) State DESTROY 2017-05-02 11:13:34.620524 [DEBUG] mod_sofia.c:343 sofia/internal172/202@pbx.fusionrnd.jennyme.co.za SOFIA DESTROY 2017-05-02 11:13:34.620524 [DEBUG] switch_core_state_machine.c:181 sofia/internal172/202@pbx.fusionrnd.jennyme.co.za Standard DESTROY 2017-05-02 11:13:34.620524 [DEBUG] switch_core_state_machine.c:751 (sofia/internal172/202@pbx.fusionrnd.jennyme.co.za) State DESTROY going to sleep recv 706 bytes from udp/[154.126.201.27]:5060 at 11:13:46.845852: ------------------------------------------------------------------------ OPTIONS sip:gw+e94f53d4-76a5-49ab-a50a-f489a3bba43c@154.126.201.123:5080;transport=udp;gw=e94f53d4-76a5-49ab-a50a-f489a3bba43c SIP/2.0 Via: SIP/2.0/UDP 154.126.201.27:5060;branch=z9hG4bK65640c5f;rport From: "asterisk" ;tag=as0c8c0859 To: Contact: Call-ID: 0ea565b0321fa7cd1d6cd29a07d4e6f3@154.126.201.27 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Tue, 02 May 2017 09:13:46 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces Content-Length: 0 ------------------------------------------------------------------------ send 742 bytes to udp/[154.126.201.27]:5060 at 11:13:46.846216: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 154.126.201.27:5060;branch=z9hG4bK65640c5f;rport=5060 From: "asterisk" ;tag=as0c8c0859 To: ;tag=Uv7mX76gZe75r Call-ID: 0ea565b0321fa7cd1d6cd29a07d4e6f3@154.126.201.27 CSeq: 102 OPTIONS Contact: User-Agent: FreeSWITCH Accept: application/sdp Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY Supported: timer, path, replaces Allow-Events: talk, hold, conference, refer Content-Length: 0 ------------------------------------------------------------------------ freeswitch@fusionpbx3>