blue.box - how to forward DID to External Number?

685 views

Skip to first unread message

Ben Sigman

unread,

Aug 21, 2012, 6:04:28 AM8/21/12

to 2600h...@googlegroups.com

I've been on the IRC chat for a few days now on Freenode trying to get some help with this...

I have a DID that will ring to an extension, but I cannot get it to ring an external destination. Instead, it is going to a bb voicemail box on an extension.

I have inbound and outbound trunks configured properly.

I have the inbound DID assigned to an external destination (cell phone) and added to the number manager as "external".

What am I missing?

Here is where it seems to fail in the logs with the error NO ROUTE DESTINATION before it goes to the bb voicemail:

2012-08-21 13:59:45.795672 [NOTICE] mod_sofia.c:1907 Pre-Answer sofia/sipinterface_3/<CID-INBOUND-CALL>@<IP-of-VoIP-Provider>!

2012-08-21 13:59:45.795672 [DEBUG] switch_core_session.c:702 Send signal sofia/sipinterface_3/<External-Destination-Cell-Phone> [BREAK]

2012-08-21 13:59:45.795672 [DEBUG] switch_core_session.c:641 Send signal sofia/sipinterface_3/<CID-INBOUND-CALL>@<IP-of-VoIP-Provider> [BREAK]

2012-08-21 13:59:45.795672 [DEBUG] switch_ivr_originate.c:3148 Originate Resulted in Success: [sofia/sipinterface_3/<External-Destination-Cell-Phone>]

2012-08-21 13:59:45.795672 [DEBUG] switch_core_session.c:641 Send signal sofia/sipinterface_3/<External-Destination-Cell-Phone> [BREAK]

2012-08-21 13:59:45.795672 [DEBUG] switch_core_session.c:641 Send signal sofia/sipinterface_3/<CID-INBOUND-CALL>@<IP-of-VoIP-Provider> [BREAK]

2012-08-21 13:59:45.795672 [DEBUG] switch_ivr_bridge.c:1182 (sofia/sipinterface_3/<External-Destination-Cell-Phone>) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA

2012-08-21 13:59:45.795672 [DEBUG] switch_core_session.c:1021 Send signal sofia/sipinterface_3/<External-Destination-Cell-Phone> [BREAK]

2012-08-21 13:59:45.795672 [DEBUG] switch_core_state_machine.c:314 (sofia/sipinterface_3/<External-Destination-Cell-Phone>) Running State Change CS_EXCHANGE_MEDIA

2012-08-21 13:59:45.795672 [DEBUG] sofia.c:4148 Channel sofia/sipinterface_3/<CID-INBOUND-CALL>@<IP-of-VoIP-Provider> skipping state [early][183]

2012-08-21 13:59:45.795672 [DEBUG] switch_core_state_machine.c:351 (sofia/sipinterface_3/<External-Destination-Cell-Phone>) State EXCHANGE_MEDIA

2012-08-21 13:59:45.795672 [DEBUG] mod_sofia.c:507 SOFIA EXCHANGE_MEDIA

2012-08-21 13:59:46.033121 [DEBUG] switch_rtp.c:2066 Correct ip/port confirmed.

12-08-21 13:59:49.000910 [DEBUG] sofia.c:4153 Channel sofia/sipinterface_3/<External-Destination-Cell-Phone> entering state [terminated][404]

2012-08-21 13:59:49.000910 [NOTICE] sofia.c:4789 Hangup sofia/sipinterface_3/<External-Destination-Cell-Phone> [CS_EXCHANGE_MEDIA] [NO_ROUTE_DESTINATION]

2012-08-21 13:59:49.000910 [DEBUG] switch_channel.c:2102 Send signal sofia/sipinterface_3/<External-Destination-Cell-Phone> [KILL]

2012-08-21 13:59:49.000910 [DEBUG] switch_core_session.c:1021 Send signal sofia/sipinterface_3/<External-Destination-Cell-Phone> [BREAK]

2012-08-21 13:59:49.012908 [DEBUG] switch_ivr_bridge.c:478 sofia/sipinterface_3/<External-Destination-Cell-Phone> ending bridge by request from read function

2012-08-21 13:59:49.012908 [DEBUG] switch_ivr_bridge.c:565 BRIDGE THREAD DONE [sofia/sipinterface_3/<External-Destination-Cell-Phone>]

2012-08-21 13:59:49.012908 [DEBUG] switch_ivr_bridge.c:585 Send signal sofia/sipinterface_3/<CID-INBOUND-CALL>@<IP-of-VoIP-Provider> [BREAK]

2012-08-21 13:59:49.012908 [DEBUG] switch_core_state_machine.c:351 (sofia/sipinterface_3/<External-Destination-Cell-Phone>) State EXCHANGE_MEDIA going to sleep

2012-08-21 13:59:49.012908 [DEBUG] switch_ivr_bridge.c:472 sofia/sipinterface_3/<External-Destination-Cell-Phone> ending bridge by request from write function

2012-08-21 13:59:49.012908 [DEBUG] switch_core_state_machine.c:314 (sofia/sipinterface_3/<External-Destination-Cell-Phone>) Running State Change CS_HANGUP

2012-08-21 13:59:49.012908 [DEBUG] switch_core_session.c:641 Send signal sofia/sipinterface_3/<CID-INBOUND-CALL>@<IP-of-VoIP-Provider> [BREAK]

2012-08-21 13:59:49.012908 [DEBUG] switch_ivr_bridge.c:565 BRIDGE THREAD DONE [sofia/sipinterface_3/<CID-INBOUND-CALL>@<IP-of-VoIP-Provider>]

2012-08-21 13:59:49.012908 [DEBUG] switch_ivr_bridge.c:585 Send signal sofia/sipinterface_3/<External-Destination-Cell-Phone> [BREAK]

2012-08-21 13:59:49.012908 [DEBUG] switch_core_state_machine.c:499 (sofia/sipinterface_3/<External-Destination-Cell-Phone>) State HANGUP

2012-08-21 13:59:49.012908 [DEBUG] mod_sofia.c:408 sofia/sipinterface_3/<External-Destination-Cell-Phone> Overriding SIP cause 404 with 404 from the other leg

2012-08-21 13:59:49.012908 [DEBUG] mod_sofia.c:414 Channel sofia/sipinterface_3/<External-Destination-Cell-Phone> hanging up, cause: NO_ROUTE_DESTINATION

Ben Sigman

unread,

Aug 21, 2012, 11:32:22 AM8/21/12

to 2600h...@googlegroups.com

Update: apparently, no outbound calls are working due to this NO_ROUTE_DESTINATION - what do I need to do to make outbound calls?

Darren Schreiber

unread,

Aug 21, 2012, 11:56:43 AM8/21/12

to 2600h...@googlegroups.com

Can you please repeat this debug but FIRST in the FreeSWITCH CLI type "sofia global siptrace on"? I need to see the SIP packets.

But from first guess it looks like this routed to your provider and they rejected it with NO_ROUTE_DESTINATION

Darren Schreiber

CEO / Co-Founder

visit: www.2600hz.com

sip:dar...@2600hz.com

tel: 415-886-7901

Ben Sigman

unread,

Aug 21, 2012, 2:32:46 PM8/21/12

to 2600h...@googlegroups.com

Thanks Darren!

Odd though... Command will not go through, I'm getting:

freeswitch@internal> sofia global siptrace on

Unknown Command [global]

Darren Schreiber

unread,

Aug 21, 2012, 3:56:01 PM8/21/12

to 2600h...@googlegroups.com

that is insanely strange – what version of FreeSWITCH is this? That command works on recent builds…

Ben Sigman

unread,

Aug 23, 2012, 2:35:19 AM8/23/12

to 2600h...@googlegroups.com

FreeSWITCH Version 1.0.6 (hacked-20120625T105750Z)

Ben Sigman

unread,

Aug 23, 2012, 4:42:19 AM8/23/12

to 2600h...@googlegroups.com

I updated freeswitch to:

FreeSWITCH Version 1.3.0+git~20120822T230855Z~3d34215919 (1.3.0; git at commit 3d34215919 on Wed, 22 Aug 2012 23:08:55 Z)

Here are my sip packets <privatized> when I attempt to make a call to the DID:

+OK Global siptrace on

------------------------------------------------------------------------

recv 901 bytes from udp/[<IP-of-INBOUND-TRUNK-SERVICE>]:5060 at 08:35:08.948805:

------------------------------------------------------------------------

INVITE sip:<DID>@<IP-of-BLUEBOX-SERVER>:5080 SIP/2.0

Via: SIP/2.0/UDP <IP-of-INBOUND-TRUNK-SERVICE>:5060;branch=z9hG4bK2ec3db57;rport

From: "<INCOMING-CID>" <sip:<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE>>;tag=as3fe91568

To: <sip:<DID>@<IP-of-BLUEBOX-SERVER>:5080>

Contact: <sip:<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE>>

Call-ID: 1aee51191525302c29823a4870cfde3c@<IP-of-INBOUND-TRUNK-SERVICE>

CSeq: 102 INVITE

User-Agent: packetrino

Max-Forwards: 70

Date: Thu, 23 Aug 2012 08:35:07 GMT

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO

Supported: replaces

Content-Type: application/sdp

Content-Length: 334

v=0

o=root 2025 2025 IN IP4 <IP-of-INBOUND-TRUNK-SERVICE>

s=session

c=IN IP4 <IP-of-INBOUND-TRUNK-SERVICE>

t=0 0

m=audio 19056 RTP/AVP 0 8 3 18 101

a=rtpmap:0 PCMU/8000

a=rtpmap:8 PCMA/8000

a=rtpmap:3 GSM/8000

a=rtpmap:18 G729/8000

a=fmtp:18 annexb=no

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-16

a=silenceSupp:off - - - -

a=ptime:20

a=sendrecv

------------------------------------------------------------------------

send 329 bytes to udp/[<IP-of-INBOUND-TRUNK-SERVICE>]:5060 at 08:35:08.949194:

------------------------------------------------------------------------

SIP/2.0 100 Trying

Via: SIP/2.0/UDP <IP-of-INBOUND-TRUNK-SERVICE>:5060;branch=z9hG4bK2ec3db57;rport=5060

From: "<INCOMING-CID>" <sip:<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE>>;tag=as3fe91568

To: <sip:<DID>@<IP-of-BLUEBOX-SERVER>:5080>

Call-ID: 1aee51191525302c29823a4870cfde3c@<IP-of-INBOUND-TRUNK-SERVICE>

CSeq: 102 INVITE

User-Agent: Configured by 2600hz!

Content-Length: 0

------------------------------------------------------------------------

2012-08-23 12:35:08.942037 [NOTICE] switch_channel.c:946 New Channel sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> [727ce21a-ecfd-11e1-9c81-3fb915ea5316]

2012-08-23 12:35:08.942037 [INFO] mod_dialplan_xml.c:485 Processing <INCOMING-CID> <<INCOMING-CID>>-><DID> in context multitenant_routing_context

2012-08-23 12:35:08.942037 [NOTICE] switch_ivr.c:1738 Transfer sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> to XML[<DID>@context_3]

2012-08-23 12:35:08.942037 [INFO] mod_dialplan_xml.c:485 Processing <INCOMING-CID> <<INCOMING-CID>>-><DID> in context context_3

2012-08-23 12:35:08.942037 [ERR] switch_core_session.c:2359 Invalid Application hash

2012-08-23 12:35:08.942037 [NOTICE] switch_core_session.c:2360 Hangup sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> [CS_EXECUTE] [DESTINATION_OUT_OF_ORDER]

send 853 bytes to udp/[<IP-of-INBOUND-TRUNK-SERVICE>]:5060 at 08:35:08.954553:

------------------------------------------------------------------------

SIP/2.0 502 Bad Gateway

Via: SIP/2.0/UDP <IP-of-INBOUND-TRUNK-SERVICE>:5060;branch=z9hG4bK2ec3db57;rport=5060

Max-Forwards: 69

From: "<INCOMING-CID>" <sip:<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE>>;tag=as3fe91568

To: <sip:<DID>@<IP-of-BLUEBOX-SERVER>:5080>;tag=FHm5ySy05X1aj

Call-ID: 1aee51191525302c29823a4870cfde3c@<IP-of-INBOUND-TRUNK-SERVICE>

CSeq: 102 INVITE

User-Agent: Configured by 2600hz!

Accept: application/sdp

Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE

Supported: precondition, path, replaces

Allow-Events: talk, hold, conference, presence, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer

Reason: Q.850;cause=27;text="DESTINATION_OUT_OF_ORDER"

Content-Length: 0

Remote-Party-ID: "<DID>" <sip:<DID>@<IP-of-BLUEBOX-SERVER>>;party=calling;privacy=off;screen=no

------------------------------------------------------------------------

2012-08-23 12:35:08.942037 [NOTICE] switch_core_session.c:1433 Session 3 (sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE>) Ended

2012-08-23 12:35:08.942037 [NOTICE] switch_core_session.c:1437 Close Channel sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> [CS_DESTROY]

recv 414 bytes from udp/[<IP-of-INBOUND-TRUNK-SERVICE>]:5060 at 08:35:08.989226:

------------------------------------------------------------------------

ACK sip:<DID>@<IP-of-BLUEBOX-SERVER>:5080 SIP/2.0

Via: SIP/2.0/UDP <IP-of-INBOUND-TRUNK-SERVICE>:5060;branch=z9hG4bK2ec3db57;rport

From: "<INCOMING-CID>" <sip:<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE>>;tag=as3fe91568

To: <sip:<DID>@<IP-of-BLUEBOX-SERVER>:5080>;tag=FHm5ySy05X1aj

Contact: <sip:<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE>>

Call-ID: 1aee51191525302c29823a4870cfde3c@<IP-of-INBOUND-TRUNK-SERVICE>

CSeq: 102 ACK

User-Agent: packetrino

Max-Forwards: 70

Content-Length: 0

Ben Sigman

unread,

Aug 23, 2012, 5:06:27 AM8/23/12

to 2600h...@googlegroups.com

Ok. I added <load module="mod_hash"/> to /usr/local/freeswitch/conf/autoload_configs/modules.conf.xml

Now, I'm getting somewhere. The DID seems to try to ring the external number for a few seconds, then it goes to the voicemail specified in the Bluebox GUI.

Here are some more SIP dumps <privatized>:

------------------------------------------------------------------------

recv 901 bytes from udp/[<IP-of-INBOUND-TRUNK-SERVICE>]:5060 at 08:48:21.053067:

------------------------------------------------------------------------

INVITE sip:<DID>@<IP-of-BLUEBOX-SERVER>:5080 SIP/2.0

Via: SIP/2.0/UDP <IP-of-INBOUND-TRUNK-SERVICE>:5060;branch=z9hG4bK1fb43214;rport

From: "<INCOMING-CID>" <sip:<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE>>;tag=as7556710d

To: <sip:<DID>@<IP-of-BLUEBOX-SERVER>:5080>

Contact: <sip:<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE>>

Call-ID: 3ac59ea51a742ec1050833fe2a44b3d1@<IP-of-INBOUND-TRUNK-SERVICE>

CSeq: 102 INVITE

User-Agent: packetrino

Max-Forwards: 70

Date: Thu, 23 Aug 2012 08:48:20 GMT

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO

Supported: replaces

Content-Type: application/sdp

Content-Length: 334

v=0

o=root 2025 2025 IN IP4 <IP-of-INBOUND-TRUNK-SERVICE>

s=session

c=IN IP4 <IP-of-INBOUND-TRUNK-SERVICE>

t=0 0

m=audio 14102 RTP/AVP 0 8 3 18 101

a=rtpmap:0 PCMU/8000

a=rtpmap:8 PCMA/8000

a=rtpmap:3 GSM/8000

a=rtpmap:18 G729/8000

a=fmtp:18 annexb=no

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-16

a=silenceSupp:off - - - -

a=ptime:20

a=sendrecv

------------------------------------------------------------------------

send 329 bytes to udp/[<IP-of-INBOUND-TRUNK-SERVICE>]:5060 at 08:48:21.053436:

------------------------------------------------------------------------

SIP/2.0 100 Trying

Via: SIP/2.0/UDP <IP-of-INBOUND-TRUNK-SERVICE>:5060;branch=z9hG4bK1fb43214;rport=5060

From: "<INCOMING-CID>" <sip:<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE>>;tag=as7556710d

To: <sip:<DID>@<IP-of-BLUEBOX-SERVER>:5080>

Call-ID: 3ac59ea51a742ec1050833fe2a44b3d1@<IP-of-INBOUND-TRUNK-SERVICE>

CSeq: 102 INVITE

User-Agent: Configured by 2600hz!

Content-Length: 0

------------------------------------------------------------------------

2012-08-23 12:48:21.042035 [DEBUG] switch_core_session.c:905 Send signal N/A [BREAK]

2012-08-23 12:48:21.042035 [DEBUG] switch_core_state_machine.c:398 () Running State Change CS_NEW

2012-08-23 12:48:21.042035 [DEBUG] switch_core_state_machine.c:416 (N/A) State NEW

2012-08-23 12:48:21.042035 [NOTICE] switch_channel.c:946 New Channel sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> [4a9e65dc-ecff-11e1-8f8d-1318e385625d]

2012-08-23 12:48:21.042035 [DEBUG] sofia.c:6094 Channel sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> entering state [received][100]

2012-08-23 12:48:21.042035 [DEBUG] sofia.c:6105 Remote SDP:

v=0

o=root 2025 2025 IN IP4 <IP-of-INBOUND-TRUNK-SERVICE>

s=session

c=IN IP4 <IP-of-INBOUND-TRUNK-SERVICE>

t=0 0

m=audio 14102 RTP/AVP 0 8 3 18 101

a=rtpmap:0 PCMU/8000

a=rtpmap:8 PCMA/8000

a=rtpmap:3 GSM/8000

a=rtpmap:18 G729/8000

a=fmtp:18 annexb=no

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-16

a=silenceSupp:off - - - -

a=ptime:20

2012-08-23 12:48:21.042035 [DEBUG] sofia_glue.c:3957 Looking for zrtp-hash

2012-08-23 12:48:21.042035 [DEBUG] sofia_glue.c:3935 Deciding whether to pass zrtp-hash between legs

2012-08-23 12:48:21.042035 [DEBUG] sofia_glue.c:3937 CF_ZRTP_PASSTHRU_REQ not set, so not propagating zrtp-hash

2012-08-23 12:48:21.042035 [DEBUG] sofia_glue.c:5053 Audio Codec Compare [PCMU:0:8000:20:64000]/[PCMU:0:8000:20:64000]

2012-08-23 12:48:21.042035 [DEBUG] sofia_glue.c:3037 Set Codec sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> PCMU/8000 20 ms 160 samples 64000 bits

2012-08-23 12:48:21.042035 [DEBUG] switch_core_codec.c:111 sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> Original read codec set to PCMU:0

2012-08-23 12:48:21.042035 [DEBUG] sofia_glue.c:5174 Set 2833 dtmf send/recv payload to 101

2012-08-23 12:48:21.042035 [DEBUG] sofia.c:6335 (sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE>) State Change CS_NEW -> CS_INIT

2012-08-23 12:48:21.042035 [DEBUG] switch_core_session.c:1210 Send signal sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> [BREAK]

2012-08-23 12:48:21.042035 [DEBUG] switch_core_state_machine.c:398 (sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE>) Running State Change CS_INIT

2012-08-23 12:48:21.042035 [DEBUG] switch_core_state_machine.c:437 (sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE>) State INIT

2012-08-23 12:48:21.042035 [DEBUG] mod_sofia.c:85 sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> SOFIA INIT

2012-08-23 12:48:21.042035 [DEBUG] mod_sofia.c:125 (sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE>) State Change CS_INIT -> CS_ROUTING

2012-08-23 12:48:21.042035 [DEBUG] switch_core_session.c:1210 Send signal sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> [BREAK]

2012-08-23 12:48:21.042035 [DEBUG] switch_core_state_machine.c:437 (sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE>) State INIT going to sleep

2012-08-23 12:48:21.042035 [DEBUG] switch_core_state_machine.c:398 (sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE>) Running State Change CS_ROUTING

2012-08-23 12:48:21.042035 [DEBUG] switch_channel.c:1939 (sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE>) Callstate Change DOWN -> RINGING

2012-08-23 12:48:21.042035 [DEBUG] switch_core_state_machine.c:446 (sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE>) State ROUTING

2012-08-23 12:48:21.042035 [DEBUG] mod_sofia.c:148 sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> SOFIA ROUTING

2012-08-23 12:48:21.042035 [DEBUG] switch_core_state_machine.c:117 sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> Standard ROUTING

2012-08-23 12:48:21.042035 [INFO] mod_dialplan_xml.c:485 Processing <INCOMING-CID> <<INCOMING-CID>>-><DID> in context multitenant_routing_context

Dialplan: sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> parsing [multitenant_routing_context->route_number_15] continue=true

Dialplan: sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> Regex (PASS) [route_number_15] destination_number(<DID>) =~ /^<DID>$/ break=on-false

Dialplan: sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> Action set(vm-operator-extension=<DID>)

Dialplan: sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> Action transfer(<DID> XML context_3)

2012-08-23 12:48:21.042035 [DEBUG] switch_core_state_machine.c:167 (sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE>) State Change CS_ROUTING -> CS_EXECUTE

2012-08-23 12:48:21.042035 [DEBUG] switch_core_session.c:1210 Send signal sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> [BREAK]

2012-08-23 12:48:21.042035 [DEBUG] switch_core_state_machine.c:446 (sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE>) State ROUTING going to sleep

2012-08-23 12:48:21.042035 [DEBUG] switch_core_state_machine.c:398 (sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE>) Running State Change CS_EXECUTE

2012-08-23 12:48:21.042035 [DEBUG] switch_core_state_machine.c:453 (sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE>) State EXECUTE

2012-08-23 12:48:21.042035 [DEBUG] mod_sofia.c:241 sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> SOFIA EXECUTE

2012-08-23 12:48:21.042035 [DEBUG] switch_core_state_machine.c:209 sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> Standard EXECUTE

EXECUTE sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> set(vm-operator-extension=<DID>)

2012-08-23 12:48:21.042035 [DEBUG] mod_dptools.c:1319 sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> SET [vm-operator-extension]=[<DID>]

EXECUTE sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> transfer(<DID> XML context_3)

2012-08-23 12:48:21.042035 [DEBUG] switch_ivr.c:1732 (sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE>) State Change CS_EXECUTE -> CS_ROUTING

2012-08-23 12:48:21.042035 [DEBUG] switch_core_session.c:1210 Send signal sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> [BREAK]

2012-08-23 12:48:21.042035 [DEBUG] switch_core_session.c:759 Send signal sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> [BREAK]

2012-08-23 12:48:21.042035 [NOTICE] switch_ivr.c:1738 Transfer sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> to XML[<DID>@context_3]

2012-08-23 12:48:21.042035 [DEBUG] switch_core_state_machine.c:453 (sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE>) State EXECUTE going to sleep

2012-08-23 12:48:21.042035 [DEBUG] switch_core_state_machine.c:398 (sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE>) Running State Change CS_ROUTING

2012-08-23 12:48:21.042035 [DEBUG] switch_core_state_machine.c:446 (sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE>) State ROUTING

2012-08-23 12:48:21.042035 [DEBUG] mod_sofia.c:148 sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> SOFIA ROUTING

2012-08-23 12:48:21.042035 [DEBUG] switch_core_state_machine.c:117 sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> Standard ROUTING

2012-08-23 12:48:21.042035 [INFO] mod_dialplan_xml.c:485 Processing <INCOMING-CID> <<INCOMING-CID>>-><DID> in context context_3

Dialplan: sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> parsing [context_3->conditioning_callerid] continue=true

Dialplan: sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> Regex (FAIL) [conditioning_callerid] ${internal_caller_id_number}() =~ /^.+$/ break=on-false

Dialplan: sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> parsing [context_3->postroute_global] continue=true

Dialplan: sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> Absolute Condition [postroute_global]

Dialplan: sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> Action hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid})

Dialplan: sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})

Dialplan: sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> Action hash(insert/${domain_name}-last_dial/global/${uuid})

Dialplan: sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})

Dialplan: sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> parsing [context_3->main_number_15] continue=true

Dialplan: sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> Regex (PASS) [main_number_15] destination_number(<DID>) =~ /^<DID>$/ break=on-false

Dialplan: sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> Action set(hangup_after_bridge=true)

Dialplan: sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> Action set(continue_on_fail=true)

Dialplan: sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> Action set(call_timeout=30)

Dialplan: sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> Action set(ringback=${us-ring})

Dialplan: sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> Action set(transfer_ringback=${us-ring})

Dialplan: sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> Action export(sip_callee_id_name=<BB-USER-ID>)

Dialplan: sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> Action export(sip_callee_id_number=<DID>)

Dialplan: sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> Action bridge(sofia/gateway/trunk_2/<NUM-OF-EXT-DESTINATION>)

Dialplan: sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> Action answer()

Dialplan: sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> Action voicemail(default voicemail_1 2010)

Dialplan: sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> Action hangup()

Dialplan: sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> parsing [context_3->main_number_14] continue=true

Dialplan: sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> Regex (FAIL) [main_number_14] destination_number(<DID>) =~ /^2010$/ break=on-false

Dialplan: sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> parsing [context_3->main_trunk_2_pattern_5] continue=true

Dialplan: sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> Regex (FAIL) [main_trunk_2_pattern_5] destination_number(<DID>) =~ /^1([0-9].+)$/ break=on-false

Dialplan: sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> parsing [context_3->main_trunk_2_pattern_1] continue=true

Dialplan: sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> Regex (PASS) [main_trunk_2_pattern_1] destination_number(<DID>) =~ /^1{0,1}([2-9][0-8][0-9][2-9][0-9]{6})$/ break=on-false

Dialplan: sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> Action set(prepend=)

Dialplan: sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> Regex (FAIL) [main_trunk_2_pattern_1] ${outbound_caller_id_number}() =~ /^.+$/ break=never

Dialplan: sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> Regex (PASS) [main_trunk_2_pattern_1] destination_number(<DID>) =~ /^1{0,1}([2-9][0-8][0-9][2-9][0-9]{6})$/ break=on-false

Dialplan: sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> Action bridge(sofia/gateway/trunk_2/${prepend}<DID>)

Dialplan: sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> parsing [context_3->main_number_8] continue=true

Dialplan: sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> Regex (FAIL) [main_number_8] destination_number(<DID>) =~ /^3109239043$/ break=on-false

Dialplan: sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> parsing [context_3->main_number_7] continue=true

Dialplan: sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> Regex (FAIL) [main_number_7] destination_number(<DID>) =~ /^2007$/ break=on-false

2012-08-23 12:48:21.042035 [DEBUG] switch_core_state_machine.c:167 (sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE>) State Change CS_ROUTING -> CS_EXECUTE

2012-08-23 12:48:21.042035 [DEBUG] switch_core_session.c:1210 Send signal sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> [BREAK]

2012-08-23 12:48:21.042035 [DEBUG] switch_core_state_machine.c:446 (sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE>) State ROUTING going to sleep

2012-08-23 12:48:21.042035 [DEBUG] switch_core_state_machine.c:398 (sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE>) Running State Change CS_EXECUTE

2012-08-23 12:48:21.042035 [DEBUG] switch_core_state_machine.c:453 (sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE>) State EXECUTE

2012-08-23 12:48:21.042035 [DEBUG] mod_sofia.c:241 sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> SOFIA EXECUTE

2012-08-23 12:48:21.042035 [DEBUG] switch_core_state_machine.c:209 sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> Standard EXECUTE

EXECUTE sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> hash(insert/<IP-of-BLUEBOX-SERVER>-spymap/<INCOMING-CID>/4a9e65dc-ecff-11e1-8f8d-1318e385625d)

EXECUTE sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> hash(insert/<IP-of-BLUEBOX-SERVER>-last_dial/<INCOMING-CID>/<DID>)

EXECUTE sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> hash(insert/<IP-of-BLUEBOX-SERVER>-last_dial/global/4a9e65dc-ecff-11e1-8f8d-1318e385625d)

EXECUTE sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> set(RFC2822_DATE=Thu, 23 Aug 2012 12:48:21 +0400)

2012-08-23 12:48:21.042035 [DEBUG] mod_dptools.c:1319 sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> SET [RFC2822_DATE]=[Thu, 23 Aug 2012 12:48:21 +0400]

EXECUTE sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> set(hangup_after_bridge=true)

2012-08-23 12:48:21.042035 [DEBUG] mod_dptools.c:1319 sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> SET [hangup_after_bridge]=[true]

EXECUTE sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> set(continue_on_fail=true)

2012-08-23 12:48:21.042035 [DEBUG] mod_dptools.c:1319 sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> SET [continue_on_fail]=[true]

EXECUTE sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> set(call_timeout=30)

2012-08-23 12:48:21.042035 [DEBUG] mod_dptools.c:1319 sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> SET [call_timeout]=[30]

EXECUTE sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> set(ringback=%(2000,4000,440.0,480.0))

2012-08-23 12:48:21.042035 [DEBUG] mod_dptools.c:1319 sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> SET [ringback]=[%(2000,4000,440.0,480.0)]

EXECUTE sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> set(transfer_ringback=%(2000,4000,440.0,480.0))

2012-08-23 12:48:21.042035 [DEBUG] mod_dptools.c:1319 sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> SET [transfer_ringback]=[%(2000,4000,440.0,480.0)]

EXECUTE sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> export(sip_callee_id_name=<BB-USER-ID>)

2012-08-23 12:48:21.042035 [DEBUG] switch_channel.c:1113 EXPORT (export_vars) [sip_callee_id_name]=[<BB-USER-ID>]

EXECUTE sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> export(sip_callee_id_number=<DID>)

2012-08-23 12:48:21.042035 [DEBUG] switch_channel.c:1113 EXPORT (export_vars) [sip_callee_id_number]=[<DID>]

EXECUTE sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> bridge(sofia/gateway/trunk_2/<NUM-OF-EXT-DESTINATION>)

2012-08-23 12:48:21.062034 [DEBUG] switch_channel.c:1067 sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> EXPORTING[export_vars] [sip_callee_id_name]=[<BB-USER-ID>] to event

2012-08-23 12:48:21.062034 [DEBUG] switch_channel.c:1067 sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> EXPORTING[export_vars] [sip_callee_id_number]=[<DID>] to event

2012-08-23 12:48:21.062034 [DEBUG] switch_ivr_originate.c:1961 Parsing global variables

2012-08-23 12:48:21.062034 [NOTICE] switch_channel.c:946 New Channel sofia/sipinterface_3/<NUM-OF-EXT-DESTINATION> [4a9fc0c6-ecff-11e1-8fa0-1318e385625d]

2012-08-23 12:48:21.062034 [DEBUG] mod_sofia.c:4786 (sofia/sipinterface_3/<NUM-OF-EXT-DESTINATION>) State Change CS_NEW -> CS_INIT

2012-08-23 12:48:21.062034 [DEBUG] switch_core_session.c:1210 Send signal sofia/sipinterface_3/<NUM-OF-EXT-DESTINATION> [BREAK]

2012-08-23 12:48:21.062034 [DEBUG] switch_core_state_machine.c:398 (sofia/sipinterface_3/<NUM-OF-EXT-DESTINATION>) Running State Change CS_INIT

2012-08-23 12:48:21.062034 [DEBUG] switch_core_state_machine.c:437 (sofia/sipinterface_3/<NUM-OF-EXT-DESTINATION>) State INIT

2012-08-23 12:48:21.062034 [DEBUG] mod_sofia.c:85 sofia/sipinterface_3/<NUM-OF-EXT-DESTINATION> SOFIA INIT

2012-08-23 12:48:21.062034 [DEBUG] sofia_glue.c:2619 Local SDP:

v=0

o=FreeSWITCH 1345681421 1345681422 IN IP4 <IP-of-BLUEBOX-SERVER>

s=FreeSWITCH

c=IN IP4 <IP-of-BLUEBOX-SERVER>

t=0 0

m=audio 30280 RTP/AVP 0 8 101 13

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-16

a=ptime:20

a=sendrecv

2012-08-23 12:48:21.062034 [DEBUG] mod_sofia.c:125 (sofia/sipinterface_3/<NUM-OF-EXT-DESTINATION>) State Change CS_INIT -> CS_ROUTING

2012-08-23 12:48:21.062034 [DEBUG] switch_core_session.c:1210 Send signal sofia/sipinterface_3/<NUM-OF-EXT-DESTINATION> [BREAK]

2012-08-23 12:48:21.062034 [DEBUG] switch_core_state_machine.c:437 (sofia/sipinterface_3/<NUM-OF-EXT-DESTINATION>) State INIT going to sleep

2012-08-23 12:48:21.062034 [DEBUG] switch_core_state_machine.c:398 (sofia/sipinterface_3/<NUM-OF-EXT-DESTINATION>) Running State Change CS_ROUTING

2012-08-23 12:48:21.062034 [DEBUG] switch_channel.c:1939 (sofia/sipinterface_3/<NUM-OF-EXT-DESTINATION>) Callstate Change DOWN -> RINGING

send 1203 bytes to udp/[<OUTBOUND-CALL-PROVIDER-IP>]:5060 at 08:48:21.063848:

------------------------------------------------------------------------

INVITE sip:<NUM-OF-EXT-DESTINATION>@<OUTBOUND-CALL-PROVIDER-DNSNAME> SIP/2.0

Via: SIP/2.0/UDP <IP-of-BLUEBOX-SERVER>:5080;rport;branch=z9hG4bKejaHF30g6c1aQ

Max-Forwards: 68

From: "<INCOMING-CID>" <sip:<OUTBOUND-CALL-TRUNK-ACCOUNT-ID>@<OUTBOUND-CALL-PROVIDER-DNSNAME>>;tag=1NBF3QZD4U2aK

To: <sip:<NUM-OF-EXT-DESTINATION>@<OUTBOUND-CALL-PROVIDER-DNSNAME>>

Call-ID: 21fe3170-67a2-1230-d8bb-a2cff5a57baa

CSeq: 32519786 INVITE

Contact: <sip:gw+trunk_2@<IP-of-BLUEBOX-SERVER>:5080;transport=udp;gw=trunk_2>

User-Agent: Configured by 2600hz!

Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE

Supported: precondition, path, replaces

Allow-Events: talk, hold, conference, presence, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer

Content-Type: application/sdp

Content-Disposition: session

Content-Length: 205

X-FS-Support: update_display,send_info

Remote-Party-ID: "<INCOMING-CID>" <sip:<INCOMING-CID>@<OUTBOUND-CALL-PROVIDER-DNSNAME>>;party=calling;screen=yes;privacy=off

v=0

o=FreeSWITCH 1345681421 1345681422 IN IP4 <IP-of-BLUEBOX-SERVER>

s=FreeSWITCH

c=IN IP4 <IP-of-BLUEBOX-SERVER>

t=0 0

m=audio 30280 RTP/AVP 0 8 101 13

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-16

a=ptime:20

------------------------------------------------------------------------

2012-08-23 12:48:21.062034 [DEBUG] switch_core_state_machine.c:446 (sofia/sipinterface_3/<NUM-OF-EXT-DESTINATION>) State ROUTING

2012-08-23 12:48:21.062034 [DEBUG] mod_sofia.c:148 sofia/sipinterface_3/<NUM-OF-EXT-DESTINATION> SOFIA ROUTING

2012-08-23 12:48:21.062034 [DEBUG] switch_ivr_originate.c:67 (sofia/sipinterface_3/<NUM-OF-EXT-DESTINATION>) State Change CS_ROUTING -> CS_CONSUME_MEDIA

2012-08-23 12:48:21.062034 [DEBUG] switch_core_session.c:1210 Send signal sofia/sipinterface_3/<NUM-OF-EXT-DESTINATION> [BREAK]

2012-08-23 12:48:21.062034 [DEBUG] switch_core_session.c:905 Send signal sofia/sipinterface_3/<NUM-OF-EXT-DESTINATION> [BREAK]

2012-08-23 12:48:21.062034 [DEBUG] switch_core_state_machine.c:446 (sofia/sipinterface_3/<NUM-OF-EXT-DESTINATION>) State ROUTING going to sleep

2012-08-23 12:48:21.062034 [DEBUG] switch_core_state_machine.c:398 (sofia/sipinterface_3/<NUM-OF-EXT-DESTINATION>) Running State Change CS_CONSUME_MEDIA

2012-08-23 12:48:21.062034 [DEBUG] switch_core_state_machine.c:465 (sofia/sipinterface_3/<NUM-OF-EXT-DESTINATION>) State CONSUME_MEDIA

2012-08-23 12:48:21.062034 [DEBUG] switch_core_state_machine.c:465 (sofia/sipinterface_3/<NUM-OF-EXT-DESTINATION>) State CONSUME_MEDIA going to sleep

2012-08-23 12:48:21.062034 [DEBUG] sofia.c:6094 Channel sofia/sipinterface_3/<NUM-OF-EXT-DESTINATION> entering state [calling][0]

recv 571 bytes from udp/[<OUTBOUND-CALL-PROVIDER-IP>]:5060 at 08:48:21.064640:

------------------------------------------------------------------------

SIP/2.0 407 Proxy Authentication Required

Via: SIP/2.0/UDP <IP-of-BLUEBOX-SERVER>:5080;branch=z9hG4bKejaHF30g6c1aQ;received=<IP-of-BLUEBOX-SERVER>;rport=5080

From: "<INCOMING-CID>" <sip:<OUTBOUND-CALL-TRUNK-ACCOUNT-ID>@<OUTBOUND-CALL-PROVIDER-DNSNAME>>;tag=1NBF3QZD4U2aK

To: <sip:<NUM-OF-EXT-DESTINATION>@<OUTBOUND-CALL-PROVIDER-DNSNAME>>;tag=as58e878a3

Call-ID: 21fe3170-67a2-1230-d8bb-a2cff5a57baa

CSeq: 32519786 INVITE

User-Agent: SGC

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO

Supported: replaces

Proxy-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="54fcf35a"

Content-Length: 0

------------------------------------------------------------------------

send 381 bytes to udp/[<OUTBOUND-CALL-PROVIDER-IP>]:5060 at 08:48:21.064911:

------------------------------------------------------------------------

ACK sip:<NUM-OF-EXT-DESTINATION>@<OUTBOUND-CALL-PROVIDER-DNSNAME> SIP/2.0

Via: SIP/2.0/UDP <IP-of-BLUEBOX-SERVER>:5080;rport;branch=z9hG4bKejaHF30g6c1aQ

Max-Forwards: 68

From: "<INCOMING-CID>" <sip:<OUTBOUND-CALL-TRUNK-ACCOUNT-ID>@<OUTBOUND-CALL-PROVIDER-DNSNAME>>;tag=1NBF3QZD4U2aK

To: <sip:<NUM-OF-EXT-DESTINATION>@<OUTBOUND-CALL-PROVIDER-DNSNAME>>;tag=as58e878a3

Call-ID: 21fe3170-67a2-1230-d8bb-a2cff5a57baa

CSeq: 32519786 ACK

Content-Length: 0

------------------------------------------------------------------------

2012-08-23 12:48:21.062034 [DEBUG] switch_core_session.c:905 Send signal sofia/sipinterface_3/<NUM-OF-EXT-DESTINATION> [BREAK]

send 1400 bytes to udp/[<OUTBOUND-CALL-PROVIDER-IP>]:5060 at 08:48:21.065285:

------------------------------------------------------------------------

INVITE sip:<NUM-OF-EXT-DESTINATION>@<OUTBOUND-CALL-PROVIDER-DNSNAME> SIP/2.0

Via: SIP/2.0/UDP <IP-of-BLUEBOX-SERVER>:5080;rport;branch=z9hG4bKFU39gyHm3NQXj

Max-Forwards: 68

From: "<INCOMING-CID>" <sip:<OUTBOUND-CALL-TRUNK-ACCOUNT-ID>@<OUTBOUND-CALL-PROVIDER-DNSNAME>>;tag=1NBF3QZD4U2aK

To: <sip:<NUM-OF-EXT-DESTINATION>@<OUTBOUND-CALL-PROVIDER-DNSNAME>>

Call-ID: 21fe3170-67a2-1230-d8bb-a2cff5a57baa

CSeq: 32519787 INVITE

Contact: <sip:gw+trunk_2@<IP-of-BLUEBOX-SERVER>:5080;transport=udp;gw=trunk_2>

User-Agent: Configured by 2600hz!

Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE

Supported: precondition, path, replaces

Allow-Events: talk, hold, conference, presence, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer

Proxy-Authorization: Digest username="<OUTBOUND-CALL-TRUNK-ACCOUNT-ID>", realm="asterisk", nonce="54fcf35a", algorithm=MD5, uri="sip:<NUM-OF-EXT-DESTINATION>@<OUTBOUND-CALL-PROVIDER-DNSNAME>", response="eb44e175c516129411ce37c7e5228f86"

Content-Type: application/sdp

Content-Disposition: session

Content-Length: 205

X-FS-Support: update_display,send_info

Remote-Party-ID: "<INCOMING-CID>" <sip:<INCOMING-CID>@<OUTBOUND-CALL-PROVIDER-DNSNAME>>;party=calling;screen=yes;privacy=off

v=0

o=FreeSWITCH 1345681421 1345681422 IN IP4 <IP-of-BLUEBOX-SERVER>

s=FreeSWITCH

c=IN IP4 <IP-of-BLUEBOX-SERVER>

t=0 0

m=audio 30280 RTP/AVP 0 8 101 13

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-16

a=ptime:20

------------------------------------------------------------------------

2012-08-23 12:48:21.062034 [DEBUG] switch_core_session.c:905 Send signal sofia/sipinterface_3/<NUM-OF-EXT-DESTINATION> [BREAK]

2012-08-23 12:48:21.062034 [DEBUG] sofia.c:6094 Channel sofia/sipinterface_3/<NUM-OF-EXT-DESTINATION> entering state [calling][0]

recv 496 bytes from udp/[<OUTBOUND-CALL-PROVIDER-IP>]:5060 at 08:48:21.066045:

------------------------------------------------------------------------

SIP/2.0 100 Trying

Via: SIP/2.0/UDP <IP-of-BLUEBOX-SERVER>:5080;branch=z9hG4bKFU39gyHm3NQXj;received=<IP-of-BLUEBOX-SERVER>;rport=5080

From: "<INCOMING-CID>" <sip:<OUTBOUND-CALL-TRUNK-ACCOUNT-ID>@<OUTBOUND-CALL-PROVIDER-DNSNAME>>;tag=1NBF3QZD4U2aK

To: <sip:<NUM-OF-EXT-DESTINATION>@<OUTBOUND-CALL-PROVIDER-DNSNAME>>

Call-ID: 21fe3170-67a2-1230-d8bb-a2cff5a57baa

CSeq: 32519787 INVITE

User-Agent: SGC

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO

Supported: replaces

Contact: <sip:<NUM-OF-EXT-DESTINATION>@<OUTBOUND-CALL-PROVIDER-IP>>

Content-Length: 0

------------------------------------------------------------------------

recv 473 bytes from udp/[<OUTBOUND-CALL-PROVIDER-IP>]:5060 at 08:48:29.473109:

------------------------------------------------------------------------

SIP/2.0 404 Not Found

Via: SIP/2.0/UDP <IP-of-BLUEBOX-SERVER>:5080;branch=z9hG4bKFU39gyHm3NQXj;received=<IP-of-BLUEBOX-SERVER>;rport=5080

From: "<INCOMING-CID>" <sip:<OUTBOUND-CALL-TRUNK-ACCOUNT-ID>@<OUTBOUND-CALL-PROVIDER-DNSNAME>>;tag=1NBF3QZD4U2aK

To: <sip:<NUM-OF-EXT-DESTINATION>@<OUTBOUND-CALL-PROVIDER-DNSNAME>>;tag=as3a326eca

Call-ID: 21fe3170-67a2-1230-d8bb-a2cff5a57baa

CSeq: 32519787 INVITE

User-Agent: SGC

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO

Supported: replaces

Content-Length: 0

------------------------------------------------------------------------

send 381 bytes to udp/[<OUTBOUND-CALL-PROVIDER-IP>]:5060 at 08:48:29.473376:

------------------------------------------------------------------------

ACK sip:<NUM-OF-EXT-DESTINATION>@<OUTBOUND-CALL-PROVIDER-DNSNAME> SIP/2.0

Via: SIP/2.0/UDP <IP-of-BLUEBOX-SERVER>:5080;rport;branch=z9hG4bKFU39gyHm3NQXj

Max-Forwards: 68

From: "<INCOMING-CID>" <sip:<OUTBOUND-CALL-TRUNK-ACCOUNT-ID>@<OUTBOUND-CALL-PROVIDER-DNSNAME>>;tag=1NBF3QZD4U2aK

To: <sip:<NUM-OF-EXT-DESTINATION>@<OUTBOUND-CALL-PROVIDER-DNSNAME>>;tag=as3a326eca

Call-ID: 21fe3170-67a2-1230-d8bb-a2cff5a57baa

CSeq: 32519787 ACK

Content-Length: 0

------------------------------------------------------------------------

2012-08-23 12:48:29.462033 [DEBUG] switch_core_session.c:905 Send signal sofia/sipinterface_3/<NUM-OF-EXT-DESTINATION> [BREAK]

2012-08-23 12:48:29.462033 [DEBUG] sofia.c:6094 Channel sofia/sipinterface_3/<NUM-OF-EXT-DESTINATION> entering state [terminated][404]

2012-08-23 12:48:29.462033 [DEBUG] switch_channel.c:2919 (sofia/sipinterface_3/<NUM-OF-EXT-DESTINATION>) Callstate Change RINGING -> HANGUP

2012-08-23 12:48:29.462033 [NOTICE] sofia.c:6889 Hangup sofia/sipinterface_3/<NUM-OF-EXT-DESTINATION> [CS_CONSUME_MEDIA] [UNALLOCATED_NUMBER]

2012-08-23 12:48:29.462033 [DEBUG] switch_ivr_originate.c:3458 Originate Resulted in Error Cause: 1 [UNALLOCATED_NUMBER]

2012-08-23 12:48:29.462033 [INFO] mod_dptools.c:3027 Originate Failed. Cause: UNALLOCATED_NUMBER

2012-08-23 12:48:29.462033 [DEBUG] switch_channel.c:2942 Send signal sofia/sipinterface_3/<NUM-OF-EXT-DESTINATION> [KILL]

2012-08-23 12:48:29.462033 [DEBUG] switch_core_session.c:1210 Send signal sofia/sipinterface_3/<NUM-OF-EXT-DESTINATION> [BREAK]

2012-08-23 12:48:29.462033 [DEBUG] switch_core_state_machine.c:398 (sofia/sipinterface_3/<NUM-OF-EXT-DESTINATION>) Running State Change CS_HANGUP

EXECUTE sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> answer()

2012-08-23 12:48:29.462033 [DEBUG] switch_core_state_machine.c:640 (sofia/sipinterface_3/<NUM-OF-EXT-DESTINATION>) State HANGUP

2012-08-23 12:48:29.462033 [DEBUG] mod_sofia.c:471 Channel sofia/sipinterface_3/<NUM-OF-EXT-DESTINATION> hanging up, cause: UNALLOCATED_NUMBER

2012-08-23 12:48:29.462033 [DEBUG] sofia_glue.c:3286 AUDIO RTP [sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE>] <IP-of-BLUEBOX-SERVER> port 25456 -> <IP-of-INBOUND-TRUNK-SERVICE> port 14102 codec: 0 ms: 20

2012-08-23 12:48:29.462033 [DEBUG] switch_rtp.c:1927 Starting timer [soft] 160 bytes per 20ms

2012-08-23 12:48:29.462033 [DEBUG] switch_core_state_machine.c:48 sofia/sipinterface_3/<NUM-OF-EXT-DESTINATION> Standard HANGUP, cause: UNALLOCATED_NUMBER

2012-08-23 12:48:29.462033 [DEBUG] switch_core_state_machine.c:640 (sofia/sipinterface_3/<NUM-OF-EXT-DESTINATION>) State HANGUP going to sleep

2012-08-23 12:48:29.462033 [DEBUG] switch_core_state_machine.c:429 (sofia/sipinterface_3/<NUM-OF-EXT-DESTINATION>) State Change CS_HANGUP -> CS_REPORTING

2012-08-23 12:48:29.462033 [DEBUG] switch_core_session.c:1210 Send signal sofia/sipinterface_3/<NUM-OF-EXT-DESTINATION> [BREAK]

2012-08-23 12:48:29.462033 [DEBUG] switch_core_state_machine.c:398 (sofia/sipinterface_3/<NUM-OF-EXT-DESTINATION>) Running State Change CS_REPORTING

2012-08-23 12:48:29.462033 [DEBUG] switch_core_state_machine.c:700 (sofia/sipinterface_3/<NUM-OF-EXT-DESTINATION>) State REPORTING

2012-08-23 12:48:29.462033 [DEBUG] switch_core_state_machine.c:92 sofia/sipinterface_3/<NUM-OF-EXT-DESTINATION> Standard REPORTING, cause: UNALLOCATED_NUMBER

2012-08-23 12:48:29.462033 [DEBUG] switch_core_state_machine.c:700 (sofia/sipinterface_3/<NUM-OF-EXT-DESTINATION>) State REPORTING going to sleep

2012-08-23 12:48:29.462033 [DEBUG] switch_core_state_machine.c:423 (sofia/sipinterface_3/<NUM-OF-EXT-DESTINATION>) State Change CS_REPORTING -> CS_DESTROY

2012-08-23 12:48:29.462033 [DEBUG] switch_core_session.c:1210 Send signal sofia/sipinterface_3/<NUM-OF-EXT-DESTINATION> [BREAK]

2012-08-23 12:48:29.462033 [DEBUG] switch_core_session.c:1415 Session 4 (sofia/sipinterface_3/<NUM-OF-EXT-DESTINATION>) Locked, Waiting on external entities

2012-08-23 12:48:29.462033 [NOTICE] switch_core_session.c:1433 Session 4 (sofia/sipinterface_3/<NUM-OF-EXT-DESTINATION>) Ended

2012-08-23 12:48:29.462033 [NOTICE] switch_core_session.c:1437 Close Channel sofia/sipinterface_3/<NUM-OF-EXT-DESTINATION> [CS_DESTROY]

2012-08-23 12:48:29.462033 [DEBUG] switch_core_state_machine.c:529 (sofia/sipinterface_3/<NUM-OF-EXT-DESTINATION>) Callstate Change HANGUP -> DOWN

2012-08-23 12:48:29.462033 [DEBUG] switch_core_state_machine.c:532 (sofia/sipinterface_3/<NUM-OF-EXT-DESTINATION>) Running State Change CS_DESTROY

2012-08-23 12:48:29.462033 [DEBUG] switch_core_state_machine.c:542 (sofia/sipinterface_3/<NUM-OF-EXT-DESTINATION>) State DESTROY

2012-08-23 12:48:29.462033 [DEBUG] mod_sofia.c:374 sofia/sipinterface_3/<NUM-OF-EXT-DESTINATION> SOFIA DESTROY

2012-08-23 12:48:29.462033 [DEBUG] switch_core_state_machine.c:99 sofia/sipinterface_3/<NUM-OF-EXT-DESTINATION> Standard DESTROY

2012-08-23 12:48:29.462033 [DEBUG] switch_core_state_machine.c:542 (sofia/sipinterface_3/<NUM-OF-EXT-DESTINATION>) State DESTROY going to sleep

2012-08-23 12:48:29.462033 [DEBUG] sofia_glue.c:3550 Set 2833 dtmf send payload to 101

2012-08-23 12:48:29.462033 [DEBUG] sofia_glue.c:3556 Set 2833 dtmf receive payload to 101

2012-08-23 12:48:29.462033 [DEBUG] sofia_glue.c:3583 sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> Set rtp dtmf delay to 40

2012-08-23 12:48:29.462033 [DEBUG] mod_sofia.c:824 Local SDP sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE>:

v=0

o=FreeSWITCH 1345686253 1345686254 IN IP4 <IP-of-BLUEBOX-SERVER>

s=FreeSWITCH

c=IN IP4 <IP-of-BLUEBOX-SERVER>

t=0 0

m=audio 25456 RTP/AVP 0 101

a=rtpmap:0 PCMU/8000

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-16

a=silenceSupp:off - - - -

a=ptime:20

a=sendrecv

2012-08-23 12:48:29.462033 [DEBUG] switch_core_session.c:759 Send signal sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> [BREAK]

2012-08-23 12:48:29.462033 [DEBUG] switch_channel.c:3320 (sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE>) Callstate Change RINGING -> ACTIVE

send 1143 bytes to udp/[<IP-of-INBOUND-TRUNK-SERVICE>]:5060 at 08:48:29.476704:

------------------------------------------------------------------------

SIP/2.0 200 OK2012-08-23 12:48:29.462033 [NOTICE] mod_dptools.c:1160 Channel [sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE>] has been answered

Via: SIP/2.0/UDP <IP-of-INBOUND-TRUNK-SERVICE>:5060;branch=z9hG4bK1fb43214;rport=5060

From: "<INCOMING-CID>" <sip:<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE>>;tag=as7556710d

To: <sip:<DID>@<IP-of-BLUEBOX-SERVER>:5080>;tag=0cjp1vea7jcrQ

Call-ID: 3ac59ea51a742ec1050833fe2a44b3d1@<IP-of-INBOUND-TRUNK-SERVICE>

CSeq: 102 INVITE

Contact: <sip:<DID>@<IP-of-BLUEBOX-SERVER>:5080;transport=udp>

User-Agent: Configured by 2600hz!

Accept: application/sdp

Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE

Supported: precondition, path, replaces

Allow-Events: talk, hold, conference, presence, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer

Content-Type: application/sdp

Content-Disposition: session

Content-Length: 249

Remote-Party-ID: "<BB-USER-ID>" <sip:<DID>@<IP-of-BLUEBOX-SERVER>>;party=calling;privacy=off;screen=no

v=0

o=FreeSWITCH 1345686253 1345686254 IN IP4 <IP-of-BLUEBOX-SERVER>

s=FreeSWITCH

c=IN IP4 <IP-of-BLUEBOX-SERVER>

t=0 0

m=audio 25456 RTP/AVP 0 101

a=rtpmap:0 PCMU/8000

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-16

a=silenceSupp:off - - - -

a=ptime:20

------------------------------------------------------------------------

2012-08-23 12:48:29.462033 [DEBUG] switch_core_session.c:905 Send signal sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> [BREAK]

2012-08-23 12:48:29.462033 [DEBUG] sofia.c:6094 Channel sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> entering state [completed][200]

EXECUTE sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> voicemail(default voicemail_1 2010)

recv 428 bytes from udp/[<IP-of-INBOUND-TRUNK-SERVICE>]:5060 at 08:48:29.511478:

------------------------------------------------------------------------

ACK sip:<DID>@<IP-of-BLUEBOX-SERVER>:5080;transport=udp SIP/2.0

Via: SIP/2.0/UDP <IP-of-INBOUND-TRUNK-SERVICE>:5060;branch=z9hG4bK1ca303c4;rport

From: "<INCOMING-CID>" <sip:<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE>>;tag=as7556710d

To: <sip:<DID>@<IP-of-BLUEBOX-SERVER>:5080>;tag=0cjp1vea7jcrQ

Contact: <sip:<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE>>

Call-ID: 3ac59ea51a742ec1050833fe2a44b3d1@<IP-of-INBOUND-TRUNK-SERVICE>

CSeq: 102 ACK

User-Agent: packetrino

Max-Forwards: 70

Content-Length: 0

------------------------------------------------------------------------

2012-08-23 12:48:29.502034 [DEBUG] switch_core_session.c:905 Send signal sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> [BREAK]

2012-08-23 12:48:29.522036 [DEBUG] sofia.c:6094 Channel sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> entering state [ready][200]

2012-08-23 12:48:29.582032 [DEBUG] switch_ivr_play_say.c:67 No language specified - Using [en]

2012-08-23 12:48:29.582032 [WARNING] switch_xml.c:2329 Invalid UTF-8 character to ampersand, skip it

2012-08-23 12:48:29.582032 [DEBUG] switch_ivr_play_say.c:244 Handle play-file:[voicemail/vm-person.wav] (en:en)

2012-08-23 12:48:29.582032 [DEBUG] switch_ivr_play_say.c:1309 Codec Activated L16@8000hz 1 channels 20ms

2012-08-23 12:48:29.742044 [DEBUG] switch_rtp.c:3596 Correct ip/port confirmed.

2012-08-23 12:48:30.942034 [DEBUG] switch_ivr_play_say.c:1682 done playing file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-person.wav

2012-08-23 12:48:31.062033 [DEBUG] switch_ivr_play_say.c:244 Handle say:[2010] (en:en)

2012-08-23 12:48:31.062033 [DEBUG] switch_ivr_play_say.c:1309 Codec Activated L16@8000hz 1 channels 20ms

2012-08-23 12:48:33.202036 [DEBUG] switch_ivr_play_say.c:1682 done playing file file_string://digits/2.wav!digits/0.wav!digits/1.wav!digits/0.wav

2012-08-23 12:48:33.302040 [DEBUG] switch_ivr_play_say.c:244 Handle play-file:[voicemail/vm-not_available.wav] (en:en)

2012-08-23 12:48:33.302040 [DEBUG] switch_ivr_play_say.c:1309 Codec Activated L16@8000hz 1 channels 20ms

recv 460 bytes from udp/[<IP-of-INBOUND-TRUNK-SERVICE>]:5060 at 08:48:34.224897:

------------------------------------------------------------------------

BYE sip:<DID>@<IP-of-BLUEBOX-SERVER>:5080;transport=udp SIP/2.0

Via: SIP/2.0/UDP <IP-of-INBOUND-TRUNK-SERVICE>:5060;branch=z9hG4bK586e2ee6;rport

From: "<INCOMING-CID>" <sip:<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE>>;tag=as7556710d

To: <sip:<DID>@<IP-of-BLUEBOX-SERVER>:5080>;tag=0cjp1vea7jcrQ

Call-ID: 3ac59ea51a742ec1050833fe2a44b3d1@<IP-of-INBOUND-TRUNK-SERVICE>

CSeq: 103 BYE

User-Agent: packetrino

Max-Forwards: 70

X-Asterisk-HangupCause: Normal Clearing

X-Asterisk-HangupCauseCode: 16

Content-Length: 0

------------------------------------------------------------------------

2012-08-23 12:48:34.222034 [DEBUG] switch_core_session.c:905 Send signal sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> [BREAK]

2012-08-23 12:48:34.242034 [DEBUG] switch_channel.c:2919 (sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE>) Callstate Change ACTIVE -> HANGUP

2012-08-23 12:48:34.242034 [NOTICE] sofia.c:711 Hangup sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> [CS_EXECUTE] [NORMAL_CLEARING]

2012-08-23 12:48:34.242034 [DEBUG] switch_channel.c:2942 Send signal sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> [KILL]

2012-08-23 12:48:34.242034 [DEBUG] switch_core_session.c:1210 Send signal sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> [BREAK]

2012-08-23 12:48:34.242034 [DEBUG] switch_ivr_play_say.c:1682 done playing file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-not_available.wav

send 491 bytes to udp/[<IP-of-INBOUND-TRUNK-SERVICE>]:5060 at 08:48:34.243386:

------------------------------------------------------------------------

SIP/2.0 200 OK

Via: SIP/2.0/UDP <IP-of-INBOUND-TRUNK-SERVICE>:5060;branch=z9hG4bK586e2ee6;rport=5060

From: "<INCOMING-CID>" <sip:<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE>>;tag=as7556710d

To: <sip:<DID>@<IP-of-BLUEBOX-SERVER>:5080>;tag=0cjp1vea7jcrQ

Call-ID: 3ac59ea51a742ec1050833fe2a44b3d1@<IP-of-INBOUND-TRUNK-SERVICE>

CSeq: 103 BYE

User-Agent: Configured by 2600hz!

Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE

Supported: precondition, path, replaces

Content-Length: 0

------------------------------------------------------------------------

2012-08-23 12:48:34.342040 [DEBUG] switch_core_session.c:2543 sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)

2012-08-23 12:48:34.342040 [DEBUG] switch_core_state_machine.c:453 (sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE>) State EXECUTE going to sleep

2012-08-23 12:48:34.342040 [DEBUG] switch_core_state_machine.c:398 (sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE>) Running State Change CS_HANGUP

2012-08-23 12:48:34.342040 [DEBUG] switch_core_state_machine.c:640 (sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE>) State HANGUP

2012-08-23 12:48:34.342040 [DEBUG] mod_sofia.c:465 sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> Overriding SIP cause 480 with 404 from the other leg

2012-08-23 12:48:34.342040 [DEBUG] mod_sofia.c:471 Channel sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> hanging up, cause: NORMAL_CLEARING

2012-08-23 12:48:34.342040 [DEBUG] switch_core_state_machine.c:48 sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> Standard HANGUP, cause: NORMAL_CLEARING

2012-08-23 12:48:34.342040 [DEBUG] switch_core_state_machine.c:640 (sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE>) State HANGUP going to sleep

2012-08-23 12:48:34.342040 [DEBUG] switch_core_state_machine.c:429 (sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE>) State Change CS_HANGUP -> CS_REPORTING

2012-08-23 12:48:34.342040 [DEBUG] switch_core_session.c:1210 Send signal sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> [BREAK]

2012-08-23 12:48:34.342040 [DEBUG] switch_core_state_machine.c:398 (sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE>) Running State Change CS_REPORTING

2012-08-23 12:48:34.342040 [DEBUG] switch_core_state_machine.c:700 (sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE>) State REPORTING

2012-08-23 12:48:34.342040 [DEBUG] switch_core_state_machine.c:92 sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> Standard REPORTING, cause: NORMAL_CLEARING

2012-08-23 12:48:34.342040 [DEBUG] switch_core_state_machine.c:700 (sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE>) State REPORTING going to sleep

2012-08-23 12:48:34.342040 [DEBUG] switch_core_state_machine.c:423 (sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE>) State Change CS_REPORTING -> CS_DESTROY

2012-08-23 12:48:34.342040 [DEBUG] switch_core_session.c:1210 Send signal sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> [BREAK]

2012-08-23 12:48:34.342040 [DEBUG] switch_core_session.c:1415 Session 3 (sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE>) Locked, Waiting on external entities

2012-08-23 12:48:34.342040 [NOTICE] switch_core_session.c:1433 Session 3 (sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE>) Ended

2012-08-23 12:48:34.342040 [NOTICE] switch_core_session.c:1437 Close Channel sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> [CS_DESTROY]

2012-08-23 12:48:34.342040 [DEBUG] switch_core_state_machine.c:529 (sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE>) Callstate Change HANGUP -> DOWN

2012-08-23 12:48:34.342040 [DEBUG] switch_core_state_machine.c:532 (sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE>) Running State Change CS_DESTROY

2012-08-23 12:48:34.342040 [DEBUG] switch_core_state_machine.c:542 (sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE>) State DESTROY

2012-08-23 12:48:34.342040 [DEBUG] mod_sofia.c:374 sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> SOFIA DESTROY

2012-08-23 12:48:34.342040 [DEBUG] switch_core_state_machine.c:99 sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE> Standard DESTROY

2012-08-23 12:48:34.342040 [DEBUG] switch_core_state_machine.c:542 (sofia/sipinterface_3/<INCOMING-CID>@<IP-of-INBOUND-TRUNK-SERVICE>) State DESTROY going to sleep

------------------------------------------------------------------------

send 809 bytes to udp/[<IP-of-INBOUND-TRUNK-SERVICE>]:5060 at 08:48:37.098262:

------------------------------------------------------------------------

Via: SIP/2.0/UDP <IP-of-BLUEBOX-SERVER>:5080;rport;branch=z9hG4bKg4v2jS2Q0yDge

Max-Forwards: 70

From: <sip:<INBOUND_SIP_ACCOUNT>@<INBOUND_SIP_SERVER>>;tag=v8cKtgBvjFKDS

To: <sip:<INBOUND_SIP_ACCOUNT>@<INBOUND_SIP_SERVER>>

Call-ID: dfda0210-ecfe-11e1-8f70-1318e385625d

CSeq: 32519711 REGISTER

Contact: <sip:gw+trunk_3@<IP-of-BLUEBOX-SERVER>:5080;transport=udp;gw=trunk_3>

Expires: 3600

User-Agent: Configured by 2600hz!

Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE

Supported: precondition, path, replaces

Authorization: Digest username="<INBOUND_SIP_ACCOUNT>", realm="asterisk", nonce="<NONCEID>", algorithm=MD5, uri="sip:<INBOUND_SIP_SERVER>;transport=udp", response="a2266db1e4e7dc24dca48bee36dd7803"

Content-Length: 0

------------------------------------------------------------------------

recv 439 bytes from udp/[<IP-of-INBOUND-TRUNK-SERVICE>]:5060 at 08:48:37.133967:

------------------------------------------------------------------------

SIP/2.0 100 Trying

Via: SIP/2.0/UDP <IP-of-BLUEBOX-SERVER>:5080;branch=z9hG4bKg4v2jS2Q0yDge;received=<IP-of-BLUEBOX-SERVER>;rport=5080

From: <sip:<INBOUND_SIP_ACCOUNT>@<INBOUND_SIP_SERVER>>;tag=v8cKtgBvjFKDS

To: <sip:<INBOUND_SIP_ACCOUNT>@<INBOUND_SIP_SERVER>>

Call-ID: dfda0210-ecfe-11e1-8f70-1318e385625d

CSeq: 32519711 REGISTER

User-Agent: packetrino

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO

Supported: replaces

Content-Length: 0

------------------------------------------------------------------------

recv 536 bytes from udp/[<IP-of-INBOUND-TRUNK-SERVICE>]:5060 at 08:48:37.134049:

------------------------------------------------------------------------

SIP/2.0 401 Unauthorized

Via: SIP/2.0/UDP <IP-of-BLUEBOX-SERVER>:5080;branch=z9hG4bKg4v2jS2Q0yDge;received=<IP-of-BLUEBOX-SERVER>;rport=5080

From: <sip:<INBOUND_SIP_ACCOUNT>@<INBOUND_SIP_SERVER>>;tag=v8cKtgBvjFKDS

To: <sip:<INBOUND_SIP_ACCOUNT>@<INBOUND_SIP_SERVER>>;tag=as34dfce77

Call-ID: dfda0210-ecfe-11e1-8f70-1318e385625d

CSeq: 32519711 REGISTER

User-Agent: packetrino

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO

Supported: replaces

WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="<NONCEID>"

Content-Length: 0

------------------------------------------------------------------------

send 809 bytes to udp/[<IP-of-INBOUND-TRUNK-SERVICE>]:5060 at 08:48:37.134191:

------------------------------------------------------------------------

Via: SIP/2.0/UDP <IP-of-BLUEBOX-SERVER>:5080;rport;branch=z9hG4bKHDpUmmKUX732S

Max-Forwards: 70

From: <sip:<INBOUND_SIP_ACCOUNT>@<INBOUND_SIP_SERVER>>;tag=v8cKtgBvjFKDS

To: <sip:<INBOUND_SIP_ACCOUNT>@<INBOUND_SIP_SERVER>>

Call-ID: dfda0210-ecfe-11e1-8f70-1318e385625d

CSeq: 32519712 REGISTER

Contact: <sip:gw+trunk_3@<IP-of-BLUEBOX-SERVER>:5080;transport=udp;gw=trunk_3>

Expires: 3600

User-Agent: Configured by 2600hz!

Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE

Supported: precondition, path, replaces

Authorization: Digest username="<INBOUND_SIP_ACCOUNT>", realm="asterisk", nonce="<NONCEID>", algorithm=MD5, uri="sip:<INBOUND_SIP_SERVER>;transport=udp", response="1e7c5aa6d3a2162f9f6e7eb6a8ec8534"

Content-Length: 0

------------------------------------------------------------------------

recv 439 bytes from udp/[<IP-of-INBOUND-TRUNK-SERVICE>]:5060 at 08:48:37.168785:

------------------------------------------------------------------------

SIP/2.0 100 Trying

Via: SIP/2.0/UDP <IP-of-BLUEBOX-SERVER>:5080;branch=z9hG4bKHDpUmmKUX732S;received=<IP-of-BLUEBOX-SERVER>;rport=5080

From: <sip:<INBOUND_SIP_ACCOUNT>@<INBOUND_SIP_SERVER>>;tag=v8cKtgBvjFKDS

To: <sip:<INBOUND_SIP_ACCOUNT>@<INBOUND_SIP_SERVER>>

Call-ID: dfda0210-ecfe-11e1-8f70-1318e385625d

CSeq: 32519712 REGISTER

User-Agent: packetrino

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO

Supported: replaces

Content-Length: 0

------------------------------------------------------------------------

recv 582 bytes from udp/[<IP-of-INBOUND-TRUNK-SERVICE>]:5060 at 08:48:37.190026:

------------------------------------------------------------------------

SIP/2.0 200 OK

Via: SIP/2.0/UDP <IP-of-BLUEBOX-SERVER>:5080;branch=z9hG4bKHDpUmmKUX732S;received=<IP-of-BLUEBOX-SERVER>;rport=5080

From: <sip:<INBOUND_SIP_ACCOUNT>@<INBOUND_SIP_SERVER>>;tag=v8cKtgBvjFKDS

To: <sip:<INBOUND_SIP_ACCOUNT>@<INBOUND_SIP_SERVER>>;tag=as34dfce77

Call-ID: dfda0210-ecfe-11e1-8f70-1318e385625d

CSeq: 32519712 REGISTER

User-Agent: packetrino

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO

Supported: replaces

Expires: 60

Contact: <sip:gw+trunk_3@<IP-of-BLUEBOX-SERVER>:5080;transport=udp;gw=trunk_3>;expires=60

Date: Thu, 23 Aug 2012 08:48:36 GMT

Content-Length: 0

Darren Schreiber

unread,

Aug 23, 2012, 12:20:16 PM8/23/12

to 2600h...@googlegroups.com

2012-08-23 12:48:29.462033 [NOTICE] sofia.c:6889 Hangup sofia/sipinterface_3/<NUM-OF-EXT-DESTINATION> [CS_CONSUME_MEDIA] [UNALLOCATED_NUMBER]

That line seems to indicate that the number doesn't exist. It's confirmed by the SIP packet:

SIP/2.0 404 Not Found

Via: SIP/2.0/UDP <IP-of-BLUEBOX-SERVER>:5080;branch=z9hG4bKFU39gyHm3NQXj;received=<IP-of-BLUEBOX-SERVER>;rport=5080

From: "<INCOMING-CID>" <sip:<OUTBOUND-CALL-TRUNK-ACCOUNT-ID>@<OUTBOUND-CALL-PROVIDER-DNSNAME>>;tag=1NBF3QZD4U2aK

To: <sip:<NUM-OF-EXT-DESTINATION>@<OUTBOUND-CALL-PROVIDER-DNSNAME>>;tag=as3a326eca

Call-ID: 21fe3170-67a2-1230-d8bb-a2cff5a57baa

CSeq: 32519787 INVITE

User-Agent: SGC

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO

Supported: replaces

Content-Length: 0

That's what your carrier is sending back to us. That means they don't recognize the phone number you're trying to dial. This is not a blue.box bug really, somehow your carrier is rejecting your calls, even though you have the right user/pass.

Because you've scrubbed the OUTBOUND-CALL-TRUNK-ID from the packet I can't see how you're trying to dial but my guess is your phone number isn't in the format that they want it to be.