freeswitch@internal> version
FreeSWITCH Version 1.0.trunk (13537M)
[root@fs2 trunk]# uname -a
Linux fs2 2.6.18-128.1.10.el5 #1 SMP Thu May 7 10:39:21 EDT 2009 i686 i686 i386 GNU/Linux
[root@fs2 trunk]# cat /proc/cpuinfo
processor: 0
vendor_id: GenuineIntel
cpu family: 15
model: 3
model name: Intel(R) Pentium(R) 4 CPU 2.80GHz
stepping: 4
cpu MHz: 2800.475
cache size: 1024 KB
physical id: 0
siblings: 2
core id: 0
cpu cores: 1
apicid: 0
fdiv_bug: no
hlt_bug: no
f00f_bug: no
coma_bug: no
fpu: yes
fpu_exception: yes
cpuid level: 5
wp: yes
flags: fpu vme de pse tsc msr pae mce cx8 apic mtrr pge mca cmov pat pse36 clflush dts acpi mmx fxsr sse sse2 ss ht tm pbe constant_tsc pni monitor ds_cpl cid xtpr
bogomips: 5602.55
processor: 1
vendor_id: GenuineIntel
cpu family: 15
model: 3
model name: Intel(R) Pentium(R) 4 CPU 2.80GHz
stepping: 4
cpu MHz: 2800.475
cache size: 1024 KB
physical id: 0
siblings: 2
core id: 0
cpu cores: 1
apicid: 1
fdiv_bug: no
hlt_bug: no
f00f_bug: no
coma_bug: no
fpu: yes
fpu_exception: yes
cpuid level: 5
wp: yes
flags: fpu vme de pse tsc msr pae mce cx8 apic mtrr pge mca cmov pat pse36 clflush dts acpi mmx fxsr sse sse2 ss ht tm pbe constant_tsc pni monitor ds_cpl cid xtpr
bogomips: 5599.49
[root@fs2 trunk]# gcc --version
gcc (GCC) 4.1.2 20080704 (Red Hat 4.1.2-44)
Call Flow
User 1018 is limited to 1 call
User 1018 calls 1019
User 1018 places 1019 oh hold
User 1018 dials 1001
User 1018 gets error on softphone of too many hops
User 1018 hears error The person you have dialed is unavailable, please try your call again later.
Dialplan entries following example at
<!-- Section to limit the number of concurrent calls per user. -->
<extension name="limit" continue="true">
<condition>
<action application="limit" data="$${domain} ${sip_auth_username} ${max_calls} limit_exceeded"/>
</condition>
</extension>
<extension name="limit_exceeded">
<condition field="destination_number" expression="^limit_exceeded$">
<action application="answer"/>
<!--<action application="playback" data="/usr/local/freeswitch/sounds/en/us/callie/misc/16000/error.wav"/>-->
<action application="playback" data="/tmp/toofat.wav"/>
<action application="hangup"/>
</condition>
</extension>
User Directory object
<include>
<user id="1018" mailbox="1018">
<params>
<param name="password" value="1234"/>
<param name="vm-password" value="1018"/>
</params>
<variables>
<variable name="toll_allow" value="domestic,international,local"/>
<variable name="accountcode" value="1018"/>
<variable name="user_context" value="default"/>
<variable name="effective_caller_id_name" value="Extension 1018"/>
<variable name="effective_caller_id_number" value="1018"/>
<variable name="outbound_caller_id_name" value="$${outbound_caller_name}"/>
<variable name="outbound_caller_id_number" value="$${outbound_caller_id}"/>
<variable name="callgroup" value="techsupport"/>
<variable name="max_calls" value="1"/>
</variables>
</user>
</include>
Console Output
freeswitch@internal> sofia profile internal siptrace on
Enabled sip debugging on internal
freeswitch@internal> recv 1069 bytes from udp/[208.77.14.90]:62002 at 17:13:28.775918:
------
INVITE sip: SIP/2.0
Via: SIP/2.0/UDP 172.16.14.6:62002;branch=z9hG4bK-d8754z-19f47d097335b75e-1---d8754z-;rport
Max-Forwards: 70
Contact: <sip::62002>
To: "1019"<sip:>
From: "testing"<sip:>;tag=2b004500
Call-ID: MjJlZTc2Yzk0YmQwZmYyMDViYmI2YjgxMTllNGZmODQ.
CSeq: 1 INVITE
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO
Content-Type: application/sdp
User-Agent: eyeBeam release 1100v stamp 47073
Content-Length: 537
v=0
o=- 6 2 IN IP4 172.16.14.6
s=CounterPath eyeBeam 1.5
c=IN IP4 172.16.14.6
t=0 0
m=audio 60420 RTP/AVP 100 106 6 0 105 8 18 3 5 101
a=x-rtp-session-id:45BC5123EAECC0C6C5BE8E5352469330
a=fmtp:18 annexb=yes
a=fmtp:101 0-15
a=rtpmap:100 SPEEX/16000
a=rtpmap:106 SPEEX-FEC/16000
a=rtpmap:105 SPEEX-FEC/8000
a=rtpmap:18 G729/8000
a=rtpmap:101 telephone-event/8000
a=alt:1 3 : WdcGer00 LXUd8QZ5 172.16.14.6 60420
a=alt:2 2 : XhGKEfBU BQ+8SrNN 192.168.92.1 60420
a=alt:3 1 : eila196r /TT/wtdg 172.16.198.1 60420
a=sendrecv
------
send 355 bytes to udp/[208.77.14.90]:62002 at 17:13:28.776570:
------
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 172.16.14.6:62002;branch=z9hG4bK-d8754z-19f47d097335b75e-1---d8754z-;rport=62002;received=208.77.14.90
From: "testing"<sip:>;tag=2b004500
To: "1019"<sip:>
Call-ID: MjJlZTc2Yzk0YmQwZmYyMDViYmI2YjgxMTllNGZmODQ.
CSeq: 1 INVITE
User-Agent: Nuvio LAB Softswitch
Content-Length: 0
------
2009-06-02 12:13:28 [DEBUG] sofia.c:4379 sofia_handle_sip_i_invite() IP 208.77.14.90 Rejected by acl "domains". Falling back to Digest auth.
send 832 bytes to udp/[208.77.14.90]:62002 at 17:13:28.783180:
------
SIP/2.0 407 Proxy Authentication Required
Via: SIP/2.0/UDP 172.16.14.6:62002;branch=z9hG4bK-d8754z-19f47d097335b75e-1---d8754z-;rport=62002;received=208.77.14.90
From: "testing"<sip:>;tag=2b004500
To: "1019" <sip:>;tag=eZ2ttZFQrjDBp
Call-ID: MjJlZTc2Yzk0YmQwZmYyMDViYmI2YjgxMTllNGZmODQ.
CSeq: 1 INVITE
User-Agent: Nuvio LAB Softswitch
Accept: application/sdp
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE, REGISTER, INFO, PUBLISH
Supported: timer, precondition, path, replaces
Allow-Events: talk, presence, dialog, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Proxy-Authenticate: Digest realm="208.77.12.22", nonce="b0d07564-4f98-11de-8296-c15559c72e05", algorithm=MD5, qop="auth"
Content-Length: 0
------
recv 324 bytes from udp/[208.77.14.90]:62002 at 17:13:28.784733:
------
ACK sip: SIP/2.0
Via: SIP/2.0/UDP 172.16.14.6:62002;branch=z9hG4bK-d8754z-19f47d097335b75e-1---d8754z-;rport
To: "1019" <sip:>;tag=eZ2ttZFQrjDBp
From: "testing"<sip:>;tag=2b004500
Call-ID: MjJlZTc2Yzk0YmQwZmYyMDViYmI2YjgxMTllNGZmODQ.
CSeq: 1 ACK
Content-Length: 0
------
recv 1329 bytes from udp/[208.77.14.90]:62002 at 17:13:28.785354:
------
INVITE sip: SIP/2.0
Via: SIP/2.0/UDP 172.16.14.6:62002;branch=z9hG4bK-d8754z-3303c65090554f57-1---d8754z-;rport
Max-Forwards: 70
Contact: <sip::62002>
To: "1019"<sip:>
From: "testing"<sip:>;tag=2b004500
Call-ID: MjJlZTc2Yzk0YmQwZmYyMDViYmI2YjgxMTllNGZmODQ.
CSeq: 2 INVITE
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO
Content-Type: application/sdp
Proxy-Authorization: Digest username="1018",realm="208.77.12.22",nonce="b0d07564-4f98-11de-8296-c15559c72e05",uri="sip:",response="d4b3c3f917ea9742885ad266d2c8254f",cnonce="00cfe78201331d54f40b71040cb92d44",nc=00000001,qop=auth,algorithm=MD5
User-Agent: eyeBeam release 1100v stamp 47073
Content-Length: 537
v=0
o=- 6 2 IN IP4 172.16.14.6
s=CounterPath eyeBeam 1.5
c=IN IP4 172.16.14.6
t=0 0
m=audio 60420 RTP/AVP 100 106 6 0 105 8 18 3 5 101
a=x-rtp-session-id:45BC5123EAECC0C6C5BE8E5352469330
a=fmtp:18 annexb=yes
a=fmtp:101 0-15
a=rtpmap:100 SPEEX/16000
a=rtpmap:106 SPEEX-FEC/16000
a=rtpmap:105 SPEEX-FEC/8000
a=rtpmap:18 G729/8000
a=rtpmap:101 telephone-event/8000
a=alt:1 3 : WdcGer00 LXUd8QZ5 172.16.14.6 60420
a=alt:2 2 : XhGKEfBU BQ+8SrNN 192.168.92.1 60420
a=alt:3 1 : eila196r /TT/wtdg 172.16.198.1 60420
a=sendrecv
------
send 355 bytes to udp/[208.77.14.90]:62002 at 17:13:28.785991:
------
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 172.16.14.6:62002;branch=z9hG4bK-d8754z-3303c65090554f57-1---d8754z-;rport=62002;received=208.77.14.90
From: "testing"<sip:>;tag=2b004500
To: "1019"<sip:>
Call-ID: MjJlZTc2Yzk0YmQwZmYyMDViYmI2YjgxMTllNGZmODQ.
CSeq: 2 INVITE
User-Agent: Nuvio LAB Softswitch
Content-Length: 0
------
2009-06-02 12:13:28 [DEBUG] sofia.c:4379 sofia_handle_sip_i_invite() IP 208.77.14.90 Rejected by acl "domains". Falling back to Digest auth.
2009-06-02 12:13:28 [NOTICE] switch_channel.c:602 switch_channel_set_name() New Channel sofia/internal/ [b0d248b2-4f98-11de-8296-c15559c72e05]
2009-06-02 12:13:28 [DEBUG] sofia.c:3039 sofia_handle_sip_i_state() Channel sofia/internal/ entering state [received][100]
2009-06-02 12:13:28 [DEBUG] sofia.c:3046 sofia_handle_sip_i_state() Remote SDP:
v=0
o=- 6 2 IN IP4 172.16.14.6
s=CounterPath eyeBeam 1.5
c=IN IP4 172.16.14.6
t=0 0
m=audio 60420 RTP/AVP 100 106 6 0 105 8 18 3 5 101
a=rtpmap:100 SPEEX/16000
a=rtpmap:106 SPEEX-FEC/16000
a=rtpmap:105 SPEEX-FEC/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=yes
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=x-rtp-session-id:45BC5123EAECC0C6C5BE8E5352469330
a=alt:1 3 : WdcGer00 LXUd8QZ5 172.16.14.6 60420
a=alt:2 2 : XhGKEfBU BQ+8SrNN 192.168.92.1 60420
a=alt:3 1 : eila196r /TT/wtdg 172.16.198.1 60420
2009-06-02 12:13:28 [DEBUG] sofia_glue.c:2955 sofia_glue_negotiate_sdp() Audio Codec Compare [SPEEX:100:16000:0]/[G722:9:8000:20]
2009-06-02 12:13:28 [DEBUG] sofia_glue.c:2955 sofia_glue_negotiate_sdp() Audio Codec Compare [SPEEX-FEC:106:16000:0]/[G722:9:8000:20]
2009-06-02 12:13:28 [DEBUG] sofia_glue.c:2955 sofia_glue_negotiate_sdp() Audio Codec Compare [DVI4:6:16000:0]/[G722:9:8000:20]
2009-06-02 12:13:28 [DEBUG] sofia_glue.c:2955 sofia_glue_negotiate_sdp() Audio Codec Compare [PCMU:0:8000:0]/[G722:9:8000:20]
2009-06-02 12:13:28 [DEBUG] sofia_glue.c:2955 sofia_glue_negotiate_sdp() Audio Codec Compare [SPEEX-FEC:105:8000:0]/[G722:9:8000:20]
2009-06-02 12:13:28 [DEBUG] sofia_glue.c:2955 sofia_glue_negotiate_sdp() Audio Codec Compare [PCMA:8:8000:0]/[G722:9:8000:20]
2009-06-02 12:13:28 [DEBUG] sofia_glue.c:2955 sofia_glue_negotiate_sdp() Audio Codec Compare [G729:18:8000:0]/[G722:9:8000:20]
2009-06-02 12:13:28 [DEBUG] sofia_glue.c:2955 sofia_glue_negotiate_sdp() Audio Codec Compare [GSM:3:8000:0]/[G722:9:8000:20]
2009-06-02 12:13:28 [DEBUG] sofia_glue.c:2955 sofia_glue_negotiate_sdp() Audio Codec Compare [DVI4:5:8000:0]/[G722:9:8000:20]
2009-06-02 12:13:28 [DEBUG] sofia_glue.c:2915 sofia_glue_negotiate_sdp() Set 2833 dtmf payload to 101
2009-06-02 12:13:28 [DEBUG] sofia_glue.c:2955 sofia_glue_negotiate_sdp() Audio Codec Compare [telephone-event:101:8000:0]/[G722:9:8000:20]
2009-06-02 12:13:28 [DEBUG] sofia_glue.c:2955 sofia_glue_negotiate_sdp() Audio Codec Compare [SPEEX:100:16000:0]/[PCMU:0:8000:20]
2009-06-02 12:13:28 [DEBUG] sofia_glue.c:2955 sofia_glue_negotiate_sdp() Audio Codec Compare [SPEEX-FEC:106:16000:0]/[PCMU:0:8000:20]
2009-06-02 12:13:28 [DEBUG] sofia_glue.c:2955 sofia_glue_negotiate_sdp() Audio Codec Compare [DVI4:6:16000:0]/[PCMU:0:8000:20]
2009-06-02 12:13:28 [DEBUG] sofia_glue.c:2955 sofia_glue_negotiate_sdp() Audio Codec Compare [PCMU:0:8000:0]/[PCMU:0:8000:20]
2009-06-02 12:13:28 [DEBUG] sofia_glue.c:1913 sofia_glue_tech_set_codec() Set Codec sofia/internal/ PCMU/8000 20 ms 160 samples
2009-06-02 12:13:28 [DEBUG] sofia.c:3205 sofia_handle_sip_i_state() (sofia/internal/) State Change CS_NEW -> CS_INIT
2009-06-02 12:13:28 [DEBUG] switch_core_session.c:933 switch_core_session_signal_state_change() Send signal sofia/internal/ [BREAK]
2009-06-02 12:13:28 [DEBUG] switch_core_state_machine.c:397 switch_core_session_run() (sofia/internal/) Running State Change CS_INIT
2009-06-02 12:13:28 [DEBUG] switch_core_state_machine.c:480 switch_core_session_run() (sofia/internal/) State INIT
2009-06-02 12:13:28 [DEBUG] mod_sofia.c:83 sofia_on_init() sofia/internal/ SOFIA INIT
2009-06-02 12:13:28 [DEBUG] mod_sofia.c:111 sofia_on_init() (sofia/internal/) State Change CS_INIT -> CS_ROUTING
2009-06-02 12:13:28 [DEBUG] switch_core_session.c:933 switch_core_session_signal_state_change() Send signal sofia/internal/ [BREAK]
2009-06-02 12:13:28 [DEBUG] switch_core_state_machine.c:480 switch_core_session_run() (sofia/internal/) State INIT going to sleep
2009-06-02 12:13:28 [DEBUG] switch_core_state_machine.c:397 switch_core_session_run() (sofia/internal/) Running State Change CS_ROUTING
2009-06-02 12:13:28 [DEBUG] switch_core_state_machine.c:483 switch_core_session_run() (sofia/internal/) State ROUTING
2009-06-02 12:13:28 [DEBUG] mod_sofia.c:130 sofia_on_routing() sofia/internal/ SOFIA ROUTING
2009-06-02 12:13:28 [DEBUG] switch_core_state_machine.c:78 switch_core_standard_on_routing() sofia/internal/ Standard ROUTING
2009-06-02 12:13:28 [INFO] mod_dialplan_xml.c:252 dialplan_hunt() Processing testing->1019 in context default
Dialplan: sofia/internal/ parsing [default->911] continue=false
Dialplan: sofia/internal/ Regex (FAIL) [911] destination_number(1019) =~ /^911$/ break=on-false
Dialplan: sofia/internal/ parsing [default->limit] continue=true
Dialplan: sofia/internal/ Absolute Condition [limit]
Dialplan: sofia/internal/ Action limit(208.77.12.22 ${sip_auth_username} ${max_calls} limit_exceeded)
Dialplan: sofia/internal/ parsing [default->limit_exceeded] continue=false
Dialplan: sofia/internal/ Regex (FAIL) [limit_exceeded] destination_number(1019) =~ /^limit_exceeded$/ break=on-false
Dialplan: sofia/internal/ parsing [default->411] continue=false
Dialplan: sofia/internal/ Regex (FAIL) [411] destination_number(1019) =~ /^411$/ break=on-false
Dialplan: sofia/internal/ parsing [default->3004029_ivr] continue=false
Dialplan: sofia/internal/ Regex (FAIL) [3004029_ivr] destination_number(1019) =~ /^8164444422$/ break=on-false
Dialplan: sofia/internal/ parsing [default->3004029_test] continue=false
Dialplan: sofia/internal/ Regex (FAIL) [3004029_test] destination_number(1019) =~ /^6900$/ break=on-false
Dialplan: sofia/internal/ parsing [default->test-fifo] continue=false
Dialplan: sofia/internal/ Regex (FAIL) [test-fifo] destination_number(1019) =~ /^3000$/ break=on-false
Dialplan: sofia/internal/ parsing [default->queue2] continue=false
Dialplan: sofia/internal/ Regex (FAIL) [queue2] destination_number(1019) =~ /^3001$/ break=on-false
Dialplan: sofia/internal/ parsing [default->International] continue=false
Dialplan: sofia/internal/ Regex (FAIL) [International] destination_number(1019) =~ /^(011\d+)$/ break=on-false
Dialplan: sofia/internal/ parsing [default->Local Dial] continue=false
Dialplan: sofia/internal/ Regex (FAIL) [Local Dial] destination_number(1019) =~ /^([0-9]{7})$/ break=on-false
Dialplan: sofia/internal/ parsing [default->tollfree] continue=false
Dialplan: sofia/internal/ Regex (FAIL) [tollfree] destination_number(1019) =~ /^(18(0{2}|8{2}|7{2}|6{2})\d{7})$/ break=on-false
Dialplan: sofia/internal/ parsing [default->Long Distance] continue=false
Dialplan: sofia/internal/ Regex (FAIL) [Long Distance] destination_number(1019) =~ /^(1{0,1}\d{10})$/ break=on-false
Dialplan: sofia/internal/ parsing [default->unloop] continue=false
Dialplan: sofia/internal/ Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
Dialplan: sofia/internal/ Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
Dialplan: sofia/internal/ parsing [default->tod_example] continue=true
Dialplan: sofia/internal/ Regex (PASS) [tod_example] ${strftime(%w)}(2) =~ /^([1-5])$/ break=on-false
Dialplan: sofia/internal/ Regex (PASS) [tod_example] ${strftime(%H%M)}(1213) =~ /^((09|1[0-7])[0-5][0-9]|1800)$/ break=on-false
Dialplan: sofia/internal/ Action set(open=true)
Dialplan: sofia/internal/ parsing [default->global-intercept] continue=false
Dialplan: sofia/internal/ Regex (FAIL) [global-intercept] destination_number(1019) =~ /^886$/ break=on-false
Dialplan: sofia/internal/ parsing [default->group-intercept] continue=false
Dialplan: sofia/internal/ Regex (FAIL) [group-intercept] destination_number(1019) =~ /^\*8$/ break=on-false
Dialplan: sofia/internal/ parsing [default->intercept-ext] continue=false
Dialplan: sofia/internal/ Regex (FAIL) [intercept-ext] destination_number(1019) =~ /^\*\*(\d+)$/ break=on-false
Dialplan: sofia/internal/ parsing [default->redial] continue=false
Dialplan: sofia/internal/ Regex (FAIL) [redial] destination_number(1019) =~ /^870$/ break=on-false
Dialplan: sofia/internal/ parsing [default->global] continue=true
Dialplan: sofia/internal/ Regex (FAIL) [global] ${network_addr}(208.77.14.90) =~ /^$/ break=never
Dialplan: sofia/internal/ ANTI-Action set(use_profile=${cond(${acl(${network_addr} rfc1918)} == true ? nat : default)})
Dialplan: sofia/internal/ Regex (PASS) [global] ${numbering_plan}() =~ /^$/ break=never
Dialplan: sofia/internal/ Action set_user(default@${domain_name})
Dialplan: sofia/internal/ Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/ Regex (FAIL) [global] ${sip_has_crypto}() =~ /^(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)$/ break=never
Dialplan: sofia/internal/ Regex (FAIL) [global] ${sip_secure_media}() =~ /^true$/ break=never
Dialplan: sofia/internal/ Regex (FAIL) [global] ${sip_user_agent}(eyeBeam release 1100v stamp 47073) =~ /^PolycomSound(Point|Station)IP-S(S|P)IP_\d{3,4}-UA\/((3).(\d).(\d).(\d{4}))$/ break=never
Dialplan: sofia/internal/ Absolute Condition [global]
Dialplan: sofia/internal/ Action hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid})
Dialplan: sofia/internal/ Action hash(insert/${domain_name}-spymap/${destination_number}/${uuid})
Dialplan: sofia/internal/ Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
Dialplan: sofia/internal/ Action hash(insert/${domain_name}-last_dial/global/${uuid})
Dialplan: sofia/internal/ parsing [default->snom-demo-2] continue=false
Dialplan: sofia/internal/ Regex (FAIL) [snom-demo-2] destination_number(1019) =~ /^9001$/ break=on-false
Dialplan: sofia/internal/ parsing [default->snom-demo-1] continue=false
Dialplan: sofia/internal/ Regex (FAIL) [snom-demo-1] destination_number(1019) =~ /^9000$/ break=on-false
Dialplan: sofia/internal/ parsing [default->eavesdrop] continue=false
Dialplan: sofia/internal/ Regex (FAIL) [eavesdrop] destination_number(1019) =~ /^\*77([1-9]{1}\d{2,5})$/ break=on-false
Dialplan: sofia/internal/ parsing [default->eavesdrop] continue=false
Dialplan: sofia/internal/ Regex (FAIL) [eavesdrop] destination_number(1019) =~ /^779$/ break=on-false
Dialplan: sofia/internal/ parsing [default->call_return] continue=false
Dialplan: sofia/internal/ Regex (FAIL) [call_return] destination_number(1019) =~ /^\*69$|^869$|^lcr$/ break=on-false
Dialplan: sofia/internal/ parsing [default->del-group] continue=false
Dialplan: sofia/internal/ Regex (FAIL) [del-group] destination_number(1019) =~ /\*59([1-9]{1}\d{2,5})$/ break=on-false
Dialplan: sofia/internal/ parsing [default->add-group] continue=false
Dialplan: sofia/internal/ Regex (FAIL) [add-group] destination_number(1019) =~ /^\*58([1-9]{1}\d{2,5})$/ break=on-false
Dialplan: sofia/internal/ parsing [default->call-group-simo] continue=false
Dialplan: sofia/internal/ Regex (FAIL) [call-group-simo] destination_number(1019) =~ /^\*60([1-9]{1}\d{2,5})$/ break=on-false
Dialplan: sofia/internal/ parsing [default->call-group-order] continue=false
Dialplan: sofia/internal/ Regex (FAIL) [call-group-order] destination_number(1019) =~ /^\*51([1-9]{1}\d{2,5})$/ break=on-false
Dialplan: sofia/internal/ parsing [default->extension-intercom] continue=false
Dialplan: sofia/internal/ Regex (FAIL) [extension-intercom] destination_number(1019) =~ /^\*96([1-9]{1}\d{2,5})$/ break=on-false
Dialplan: sofia/internal/ parsing [default->Local_Extension] continue=false
Dialplan: sofia/internal/ Regex (PASS) [Local_Extension] destination_number(1019) =~ /^([1-9]{1}\d{2,5})$/ break=on-false
Dialplan: sofia/internal/ Action set(dialed_extension=1019)
Dialplan: sofia/internal/ Action export(dialed_extension=1019)
Dialplan: sofia/internal/ Regex (FAIL) [Local_Extension] destination_number(1019) =~ /^1018$/ break=on-false
Dialplan: sofia/internal/ ANTI-Action bind_meta_app(1 ab s execute_extension::dx XML features)
Dialplan: sofia/internal/ ANTI-Action bind_meta_app(2 ab s record_session::/usr/local/freeswitch/recordings/${caller_id_number}.${strftime(%Y-%m-%d-%H-%M-%S)}.wav)
Dialplan: sofia/internal/ ANTI-Action bind_meta_app(3 ab s execute_extension::cf XML features)
Dialplan: sofia/internal/ ANTI-Action set(ringback=${us-ring})
Dialplan: sofia/internal/ ANTI-Action set(transfer_ringback=local_stream://moh)
Dialplan: sofia/internal/ ANTI-Action set(call_timeout=30)
Dialplan: sofia/internal/ ANTI-Action set(hangup_after_bridge=true)
Dialplan: sofia/internal/ ANTI-Action set(continue_on_fail=true)
Dialplan: sofia/internal/ ANTI-Action db(insert/${domain_name}-call_return/${dialed_extension}/${caller_id_number})
Dialplan: sofia/internal/ ANTI-Action db(insert/${domain_name}-last_dial_ext/${dialed_extension}/${uuid})
Dialplan: sofia/internal/ ANTI-Action set(called_party_callgroup=${user_data(${dialed_extension}@${domain_name} var callgroup)})
Dialplan: sofia/internal/ ANTI-Action db(insert/${domain_name}-last_dial/${called_party_callgroup}/${uuid})
Dialplan: sofia/internal/ ANTI-Action bridge(user/${dialed_extension}@${domain_name})
Dialplan: sofia/internal/ ANTI-Action answer()
Dialplan: sofia/internal/ ANTI-Action sleep(1000)
Dialplan: sofia/internal/ ANTI-Action voicemail(default ${domain_name} ${dialed_extension})
2009-06-02 12:13:28 [DEBUG] switch_core_state_machine.c:114 switch_core_standard_on_routing() (sofia/internal/) State Change CS_ROUTING -> CS_EXECUTE
2009-06-02 12:13:28 [DEBUG] switch_core_session.c:933 switch_core_session_signal_state_change() Send signal sofia/internal/ [BREAK]
2009-06-02 12:13:28 [DEBUG] switch_core_state_machine.c:483 switch_core_session_run() (sofia/internal/) State ROUTING going to sleep
2009-06-02 12:13:28 [DEBUG] switch_core_state_machine.c:397 switch_core_session_run() (sofia/internal/) Running State Change CS_EXECUTE
2009-06-02 12:13:28 [DEBUG] switch_core_state_machine.c:490 switch_core_session_run() (sofia/internal/) State EXECUTE
2009-06-02 12:13:28 [DEBUG] mod_sofia.c:173 sofia_on_execute() sofia/internal/ SOFIA EXECUTE
2009-06-02 12:13:28 [DEBUG] switch_core_state_machine.c:151 switch_core_standard_on_execute() sofia/internal/ Standard EXECUTE
EXECUTE sofia/internal/ limit(208.77.12.22 1018 1 limit_exceeded)
EXECUTE sofia/internal/ set(open=true)
2009-06-02 12:13:28 [DEBUG] mod_dptools.c:748 set_function() sofia/internal/ SET [open]=[true]
EXECUTE sofia/internal/ set(use_profile=default)
2009-06-02 12:13:28 [DEBUG] mod_dptools.c:748 set_function() sofia/internal/ SET [use_profile]=[default]
EXECUTE sofia/internal/ set_user()
EXECUTE sofia/internal/ hash(insert/208.77.12.22-spymap/1018/b0d248b2-4f98-11de-8296-c15559c72e05)
EXECUTE sofia/internal/ hash(insert/208.77.12.22-spymap/1019/b0d248b2-4f98-11de-8296-c15559c72e05)
EXECUTE sofia/internal/ hash(insert/208.77.12.22-last_dial/1018/1019)
EXECUTE sofia/internal/ hash(insert/208.77.12.22-last_dial/global/b0d248b2-4f98-11de-8296-c15559c72e05)
EXECUTE sofia/internal/ set(dialed_extension=1019)
2009-06-02 12:13:28 [DEBUG] mod_dptools.c:748 set_function() sofia/internal/ SET [dialed_extension]=[1019]
EXECUTE sofia/internal/ export(dialed_extension=1019)
2009-06-02 12:13:28 [DEBUG] mod_dptools.c:886 export_function() EXPORT [dialed_extension]=[1019]
EXECUTE sofia/internal/ bind_meta_app(1 ab s execute_extension::dx XML features)
2009-06-02 12:13:28 [INFO] switch_ivr_async.c:1763 switch_ivr_bind_dtmf_meta_session() Bound A-Leg: 1 execute_extension::dx XML features
2009-06-02 12:13:28 [INFO] switch_ivr_async.c:1770 switch_ivr_bind_dtmf_meta_session() Bound B-Leg: 1 execute_extension::dx XML features
EXECUTE sofia/internal/ bind_meta_app(2 ab s record_session::/usr/local/freeswitch/recordings/1018.2009-06-02-12-13-28.wav)
2009-06-02 12:13:28 [INFO] switch_ivr_async.c:1763 switch_ivr_bind_dtmf_meta_session() Bound A-Leg: 2 record_session::/usr/local/freeswitch/recordings/1018.2009-06-02-12-13-28.wav
2009-06-02 12:13:28 [INFO] switch_ivr_async.c:1770 switch_ivr_bind_dtmf_meta_session() Bound B-Leg: 2 record_session::/usr/local/freeswitch/recordings/1018.2009-06-02-12-13-28.wav
EXECUTE sofia/internal/ bind_meta_app(3 ab s execute_extension::cf XML features)
2009-06-02 12:13:28 [INFO] switch_ivr_async.c:1763 switch_ivr_bind_dtmf_meta_session() Bound A-Leg: 3 execute_extension::cf XML features
2009-06-02 12:13:28 [INFO] switch_ivr_async.c:1770 switch_ivr_bind_dtmf_meta_session() Bound B-Leg: 3 execute_extension::cf XML features
EXECUTE sofia/internal/ set(ringback=%(2000, 4000, 440.0, 480.0))
2009-06-02 12:13:28 [DEBUG] mod_dptools.c:748 set_function() sofia/internal/ SET [ringback]=[%(2000, 4000, 440.0, 480.0)]
EXECUTE sofia/internal/ set(transfer_ringback=local_stream://moh)
2009-06-02 12:13:28 [DEBUG] mod_dptools.c:748 set_function() sofia/internal/ SET [transfer_ringback]=[local_stream://moh]
EXECUTE sofia/internal/ set(call_timeout=30)
2009-06-02 12:13:28 [DEBUG] mod_dptools.c:748 set_function() sofia/internal/ SET [call_timeout]=[30]
EXECUTE sofia/internal/ set(hangup_after_bridge=true)
2009-06-02 12:13:28 [DEBUG] mod_dptools.c:748 set_function() sofia/internal/ SET [hangup_after_bridge]=[true]
EXECUTE sofia/internal/ set(continue_on_fail=true)
2009-06-02 12:13:28 [DEBUG] mod_dptools.c:748 set_function() sofia/internal/ SET [continue_on_fail]=[true]
EXECUTE sofia/internal/ db(insert/208.77.12.22-call_return/1019/1018)
EXECUTE sofia/internal/ db(insert/208.77.12.22-last_dial_ext/1019/b0d248b2-4f98-11de-8296-c15559c72e05)
EXECUTE sofia/internal/ set(called_party_callgroup=techsupport)
2009-06-02 12:13:28 [DEBUG] mod_dptools.c:748 set_function() sofia/internal/ SET [called_party_callgroup]=[techsupport]
EXECUTE sofia/internal/ db(insert/208.77.12.22-last_dial/techsupport/b0d248b2-4f98-11de-8296-c15559c72e05)
EXECUTE sofia/internal/ bridge(user/)
2009-06-02 12:13:28 [DEBUG] switch_ivr_originate.c:1017 switch_ivr_originate() variable string 0 = [presence_id=
2009-06-02 12:13:28 [NOTICE] switch_channel.c:602 switch_channel_set_name() New Channel sofia/internal/sip:;fs_nat=yes;fs_path=sip%3A1019%40208.77.14.71%3A5060 [b0d970e2-4f98-11de-8296-c15559c72e05]
2009-06-02 12:13:28 [DEBUG] mod_sofia.c:2727 sofia_outgoing_channel() (sofia/internal/sip:;fs_nat=yes;fs_path=sip%3A1019%40208.77.14.71%3A5060) State Change CS_NEW -> CS_INIT
2009-06-02 12:13:28 [DEBUG] switch_core_session.c:933 switch_core_session_signal_state_change() Send signal sofia/internal/sip:;fs_nat=yes;fs_path=sip%3A1019%40208.77.14.71%3A5060 [BREAK]
2009-06-02 12:13:28 [DEBUG] switch_core_state_machine.c:397 switch_core_session_run() (sofia/internal/sip:;fs_nat=yes;fs_path=sip%3A1019%40208.77.14.71%3A5060) Running State Change CS_INIT
2009-06-02 12:13:28 [DEBUG] switch_core_state_machine.c:480 switch_core_session_run() (sofia/internal/sip:;fs_nat=yes;fs_path=sip%3A1019%40208.77.14.71%3A5060) State INIT
2009-06-02 12:13:28 [DEBUG] mod_sofia.c:83 sofia_on_init() sofia/internal/sip:;fs_nat=yes;fs_path=sip%3A1019%40208.77.14.71%3A5060 SOFIA INIT
2009-06-02 12:13:28 [DEBUG] sofia_glue.c:1599 sofia_glue_do_invite() sip::5060 Setting proxy route to sofia/internal/sip:;fs_nat=yes;fs_path=sip%3A1019%40208.77.14.71%3A5060
2009-06-02 12:13:28 [DEBUG] mod_sofia.c:111 sofia_on_init() (sofia/internal/sip:;fs_nat=yes;fs_path=sip%3A1019%40208.77.14.71%3A5060) State Change CS_INIT -> CS_ROUTING
2009-06-02 12:13:28 [DEBUG] switch_core_session.c:933 switch_core_session_signal_state_change() Send signal sofia/internal/sip:;fs_nat=yes;fs_path=sip%3A1019%40208.77.14.71%3A5060 [BREAK]
2009-06-02 12:13:28 [DEBUG] switch_core_state_machine.c:480 switch_core_session_run() (sofia/internal/sip:;fs_nat=yes;fs_path=sip%3A1019%40208.77.14.71%3A5060) State INIT going to sleep
2009-06-02 12:13:28 [DEBUG] switch_core_state_machine.c:397 switch_core_session_run() (sofia/internal/sip:;fs_nat=yes;fs_path=sip%3A1019%40208.77.14.71%3A5060) Running State Change CS_ROUTING
2009-06-02 12:13:28 [DEBUG] switch_core_state_machine.c:483 switch_core_session_run() (sofia/internal/sip:;fs_nat=yes;fs_path=sip%3A1019%40208.77.14.71%3A5060) State ROUTING
2009-06-02 12:13:28 [DEBUG] mod_sofia.c:130 sofia_on_routing() sofia/internal/sip:;fs_nat=yes;fs_path=sip%3A1019%40208.77.14.71%3A5060 SOFIA ROUTING
2009-06-02 12:13:28 [DEBUG] switch_ivr_originate.c:63 originate_on_routing() (sofia/internal/sip:;fs_nat=yes;fs_path=sip%3A1019%40208.77.14.71%3A5060) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2009-06-02 12:13:28 [DEBUG] switch_core_session.c:933 switch_core_session_signal_state_change() Send signal sofia/internal/sip:;fs_nat=yes;fs_path=sip%3A1019%40208.77.14.71%3A5060 [BREAK]
2009-06-02 12:13:28 [DEBUG] switch_core_state_machine.c:483 switch_core_session_run() (sofia/internal/sip:;fs_nat=yes;fs_path=sip%3A1019%40208.77.14.71%3A5060) State ROUTING going to sleep
2009-06-02 12:13:28 [DEBUG] switch_core_state_machine.c:397 switch_core_session_run() (sofia/internal/sip:;fs_nat=yes;fs_path=sip%3A1019%40208.77.14.71%3A5060) Running State Change CS_CONSUME_MEDIA
send 1203 bytes to udp/[208.77.14.71]:5060 at 17:13:28.837374:
------
INVITE sip: SIP/2.0
Via: SIP/2.0/UDP 208.77.12.22;rport;branch=z9hG4bKvp99t2ymajeZr
Route: <sip::5060>
Max-Forwards: 69
From: "Extension 1018" <sip:>;tag=gHNcyNHyj4SgD
To: <sip:>
Call-ID: 8837e74c-ca3b-122c-9a90-00c09f43498a
CSeq: 115863068 INVITE
Contact: <sip::5060>
User-Agent: Nuvio LAB Softswitch
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE, REGISTER, INFO, PUBLISH
Supported: timer, precondition, path, replaces
Allow-Events: talk, presence, dialog, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Content-Type: application/sdp
2009-06-02 12:13:28 [DEBUG] switch_core_state_machine.c:502 switch_core_session_run() (sofia/internal/sip:;fs_nat=yes;fs_path=sip%3A1019%40208.77.14.71%3A5060) State CONSUME_MEDIA
Content-Disposition: session
Content-Length: 333
Remote-Party-ID: "Extension 1018" <sip:>;party=calling;screen=yes;privacy=off
v=0
o=FreeSWITCH 3319448772097363991 6175946776793220347 IN IP4 208.77.12.22
s=FreeSWITCH
c=IN IP4 208.77.12.22
t=0 0
m=audio 16460 RTP/AVP 0 9 8 3 101 13
a=rtpmap:0 PCMU/8000
a=rtpmap:9 G722/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:3 GSM/80002009-06-02 12:13:28 [DEBUG] switch_ivr_originate.c:1664 switch_ivr_originate() sofia/internal/ receive message [PROGRESS]
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=rtpmap:13 CN/8000
a=ptime:20
------
2009-06-02 12:13:28 [INFO] switch_ivr_originate.c:1664 switch_ivr_originate() Sending early media
2009-06-02 12:13:28 [DEBUG] sofia_glue.c:2147 sofia_glue_activate_rtp() AUDIO RTP [sofia/internal/ 208.77.12.22 port 30164 -> 172.16.14.6 port 60420 codec: 0 ms: 20
2009-06-02 12:13:28 [DEBUG] switch_rtp.c:1128 switch_rtp_create() Starting timer [soft] 160 bytes per 20ms
2009-06-02 12:13:28 [DEBUG] sofia.c:3039 sofia_handle_sip_i_state() Channel sofia/internal/sip:;fs_nat=yes;fs_path=sip%3A1019%40208.77.14.71%3A5060 entering state [calling][0]
2009-06-02 12:13:28 [INFO] mod_sofia.c:1487 sofia_receive_message() Ring SDP:
v=0
o=FreeSWITCH 1243932644 1243932645 IN IP4 208.77.12.22
s=FreeSWITCH
c=IN IP4 208.77.12.22
t=0 0
m=audio 30164 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
2009-06-02 12:13:28 [NOTICE] mod_sofia.c:1490 sofia_receive_message() Pre-Answer sofia/internal/!
2009-06-02 12:13:28 [DEBUG] switch_core_session.c:630 switch_core_session_perform_receive_message() Send signal sofia/internal/ [BREAK]
2009-06-02 12:13:28 [DEBUG] switch_ivr_originate.c:1681 switch_ivr_originate() Raw Codec Activation Success L16@8000hz 1 channel 20ms
2009-06-02 12:13:28 [DEBUG] switch_ivr_originate.c:1740 switch_ivr_originate() Play Ringback Tone [%(2000, 4000, 440.0, 480.0)]
send 1083 bytes to udp/[208.77.14.90]:62002 at 17:13:28.839709:
------
SIP/2.0 183 Session Progress
Via: SIP/2.0/UDP 172.16.14.6:62002;branch=z9hG4bK-d8754z-3303c65090554f57-1---d8754z-;rport=62002;received=208.77.14.90
From: "testing"<sip:>;tag=2b004500
To: "1019" <sip:>;tag=F8UKvt0tNU3XH
Call-ID: MjJlZTc2Yzk0YmQwZmYyMDViYmI2YjgxMTllNGZmODQ.