recv 865 bytes from udp/[192.168.0.200]:5060 at 09:31:18.696255:
------------------------------------------------------------------------
INVITE sip:339XXXXXXXX@192.168.0.2 SIP/2.0
Via: SIP/2.0/UDP 192.168.0.200:5060;branch=z9hG4bK00be621f9475e11195f9001e68586d6b;rport
From: "1000" <sip:1000@192.168.0.2>;tag=645977894
To: <sip:339XXXXXXXX@192.168.0.2>
Call-ID: 00BE621F-9475-E111-95F8-001E68586D6B@192.168.0.200
CSeq: 5 INVITE
Contact: <sip:1000@192.168.0.200:5060>
Content-Type: application/sdp
Allow: INVITE, OPTIONS, ACK, BYE, CANCEL, INFO, NOTIFY, MESSAGE, UPDATE
Max-Forwards: 70
Supported: 100rel, replaces, from-change
User-Agent: SIPPER for PhonerLite
P-Preferred-Identity: <sip:1000@192.168.0.2>
Content-Length: 260
v=0
o=- 162748822 0 IN IP4 192.168.0.200
s=SIPPER for PhonerLite
c=IN IP4 192.168.0.200
t=0 0
m=audio 5062 RTP/AVP 8 3 0 101
a=rtpmap:8 PCMA/8000
a=rtpmap:3 GSM/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=sendrecv
------------------------------------------------------------------------
send 379 bytes to udp/[192.168.0.200]:5060 at 09:31:18.696482:
------------------------------------------------------------------------
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.0.200:5060;branch=z9hG4bK00be621f9475e11195f9001e68586d6b;rport=5060
From: "1000" <sip:1000@192.168.0.2>;tag=645977894
To: <sip:339XXXXXXXX@192.168.0.2>
Call-ID: 00BE621F-9475-E111-95F8-001E68586D6B@192.168.0.200
CSeq: 5 INVITE
User-Agent: FreeSWITCH-mod_sofia/1.0.head-git-1aa9103 2012-03-01 15-58-48 -0600
Content-Length: 0
------------------------------------------------------------------------
2012-03-26 11:31:18.692558 [DEBUG] sofia.c:7559 IP 192.168.0.200 Rejected by acl "domains". Falling back to Digest auth.
send 865 bytes to udp/[192.168.0.200]:5060 at 09:31:18.697335:
------------------------------------------------------------------------
SIP/2.0 407 Proxy Authentication Required
Via: SIP/2.0/UDP 192.168.0.200:5060;branch=z9hG4bK00be621f9475e11195f9001e68586d6b;rport=5060
From: "1000" <sip:1000@192.168.0.2>;tag=645977894
To: <sip:339XXXXXXXX@192.168.0.2>;tag=pvaSD4jQ22N9D
Call-ID: 00BE621F-9475-E111-95F8-001E68586D6B@192.168.0.200
CSeq: 5 INVITE
User-Agent: FreeSWITCH-mod_sofia/1.0.head-git-1aa9103 2012-03-01 15-58-48 -0600
Accept: application/sdp
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, precondition, path, replaces
Allow-Events: talk, hold, presence, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Proxy-Authenticate: Digest realm="192.168.0.2", nonce="710d05a6-7726-11e1-bf71-e50b8b76e101", algorithm=MD5, qop="auth"
Content-Length: 0
------------------------------------------------------------------------
recv 346 bytes from udp/[192.168.0.200]:5060 at 09:31:18.698468:
------------------------------------------------------------------------
ACK sip:339XXXXXXXX@192.168.0.2 SIP/2.0
Via: SIP/2.0/UDP 192.168.0.200:5060;branch=z9hG4bK00be621f9475e11195f9001e68586d6b;rport
From: "1000" <sip:1000@192.168.0.2>;tag=645977894
To: <sip:339XXXXXXXX@192.168.0.2>;tag=pvaSD4jQ22N9D
Call-ID: 00BE621F-9475-E111-95F8-001E68586D6B@192.168.0.200
CSeq: 5 ACK
Max-Forwards: 70
Content-Length: 0
------------------------------------------------------------------------
recv 1137 bytes from udp/[192.168.0.200]:5060 at 09:31:18.699228:
------------------------------------------------------------------------
INVITE sip:339XXXXXXXX@192.168.0.2 SIP/2.0
Via: SIP/2.0/UDP 192.168.0.200:5060;branch=z9hG4bK00be621f9475e11195fa001e68586d6b;rport
From: "1000" <sip:1000@192.168.0.2>;tag=645977894
To: <sip:339XXXXXXXX@192.168.0.2>
Call-ID: 00BE621F-9475-E111-95F8-001E68586D6B@192.168.0.200
CSeq: 6 INVITE
Contact: <sip:1000@192.168.0.200:5060>
Proxy-Authorization: Digest username="1000", realm="192.168.0.2", nonce="710d05a6-7726-11e1-bf71-e50b8b76e101", uri="sip:339XXXXXXXX@192.168.0.2", response="7378107bc277149e3b6ef00c1c766a71", algorithm=MD5, cnonce="234abcc436e2667097e7fe6eia53e8dd", qop=auth, nc=00000001
Content-Type: application/sdp
Allow: INVITE, OPTIONS, ACK, BYE, CANCEL, INFO, NOTIFY, MESSAGE, UPDATE
Max-Forwards: 70
Supported: 100rel, replaces, from-change
User-Agent: SIPPER for PhonerLite
P-Preferred-Identity: <sip:1000@192.168.0.2>
Content-Length: 260
v=0
o=- 162748822 0 IN IP4 192.168.0.200
s=SIPPER for PhonerLite
c=IN IP4 192.168.0.200
t=0 0
m=audio 5062 RTP/AVP 8 3 0 101
a=rtpmap:8 PCMA/8000
a=rtpmap:3 GSM/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=sendrecv
------------------------------------------------------------------------
send 379 bytes to udp/[192.168.0.200]:5060 at 09:31:18.699380:
------------------------------------------------------------------------
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.0.200:5060;branch=z9hG4bK00be621f9475e11195fa001e68586d6b;rport=5060
From: "1000" <sip:1000@192.168.0.2>;tag=645977894
To: <sip:339XXXXXXXX@192.168.0.2>
Call-ID: 00BE621F-9475-E111-95F8-001E68586D6B@192.168.0.200
CSeq: 6 INVITE
User-Agent: FreeSWITCH-mod_sofia/1.0.head-git-1aa9103 2012-03-01 15-58-48 -0600
Content-Length: 0
------------------------------------------------------------------------
2012-03-26 11:31:18.692558 [DEBUG] sofia.c:7559 IP 192.168.0.200 Rejected by acl "domains". Falling back to Digest auth.
2012-03-26 11:31:18.692558 [NOTICE] switch_channel.c:926 New Channel sofia/internal/1000@192.168.0.2 [710da826-7726-11e1-bf72-e50b8b76e101]
2012-03-26 11:31:18.692558 [DEBUG] sofia.c:5526 Channel sofia/internal/1000@192.168.0.2 entering state [received][100]
2012-03-26 11:31:18.692558 [DEBUG] sofia.c:5537 Remote SDP:
v=0
o=- 162748822 0 IN IP4 192.168.0.200
s=SIPPER for PhonerLite
c=IN IP4 192.168.0.200
t=0 0
m=audio 5062 RTP/AVP 8 3 0 101
a=rtpmap:8 PCMA/8000
a=rtpmap:3 GSM/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
2012-03-26 11:31:18.692558 [DEBUG] sofia_glue.c:4874 Audio Codec Compare [PCMA:8:8000:20:64000]/[G7221:115:32000:20:48000]
2012-03-26 11:31:18.692558 [DEBUG] sofia_glue.c:4874 Audio Codec Compare [PCMA:8:8000:20:64000]/[G7221:107:16000:20:32000]
2012-03-26 11:31:18.692558 [DEBUG] sofia_glue.c:4874 Audio Codec Compare [PCMA:8:8000:20:64000]/[G722:9:8000:20:64000]
2012-03-26 11:31:18.692558 [DEBUG] sofia_glue.c:4874 Audio Codec Compare [PCMA:8:8000:20:64000]/[PCMU:0:8000:20:64000]
2012-03-26 11:31:18.692558 [DEBUG] sofia_glue.c:4874 Audio Codec Compare [PCMA:8:8000:20:64000]/[PCMA:8:8000:20:64000]
2012-03-26 11:31:18.692558 [DEBUG] sofia_glue.c:2991 Set Codec sofia/internal/1000@192.168.0.2 PCMA/8000 20 ms 160 samples 64000 bits
2012-03-26 11:31:18.692558 [DEBUG] switch_core_codec.c:111 sofia/internal/1000@192.168.0.2 Original read codec set to PCMA:8
2012-03-26 11:31:18.692558 [DEBUG] sofia_glue.c:4995 Set 2833 dtmf send/recv payload to 101
2012-03-26 11:31:18.692558 [DEBUG] sofia.c:5749 (sofia/internal/1000@192.168.0.2) State Change CS_NEW -> CS_INIT
2012-03-26 11:31:18.692558 [DEBUG] switch_core_session.c:1180 Send signal sofia/internal/1000@192.168.0.2 [BREAK]
2012-03-26 11:31:18.692558 [DEBUG] switch_core_state_machine.c:362 (sofia/internal/1000@192.168.0.2) Running State Change CS_INIT
2012-03-26 11:31:18.692558 [DEBUG] switch_core_state_machine.c:401 (sofia/internal/1000@192.168.0.2) State INIT
2012-03-26 11:31:18.692558 [DEBUG] mod_sofia.c:85 sofia/internal/1000@192.168.0.2 SOFIA INIT
2012-03-26 11:31:18.692558 [DEBUG] mod_sofia.c:125 (sofia/internal/1000@192.168.0.2) State Change CS_INIT -> CS_ROUTING
2012-03-26 11:31:18.692558 [DEBUG] switch_core_session.c:1180 Send signal sofia/internal/1000@192.168.0.2 [BREAK]
2012-03-26 11:31:18.692558 [DEBUG] switch_core_state_machine.c:401 (sofia/internal/1000@192.168.0.2) State INIT going to sleep
2012-03-26 11:31:18.692558 [DEBUG] switch_core_state_machine.c:362 (sofia/internal/1000@192.168.0.2) Running State Change CS_ROUTING
2012-03-26 11:31:18.692558 [DEBUG] switch_channel.c:1886 (sofia/internal/1000@192.168.0.2) Callstate Change DOWN -> RINGING
2012-03-26 11:31:18.692558 [DEBUG] switch_core_state_machine.c:410 (sofia/internal/1000@192.168.0.2) State ROUTING
2012-03-26 11:31:18.692558 [DEBUG] mod_sofia.c:148 sofia/internal/1000@192.168.0.2 SOFIA ROUTING
2012-03-26 11:31:18.692558 [DEBUG] switch_core_state_machine.c:104 sofia/internal/1000@192.168.0.2 Standard ROUTING
2012-03-26 11:31:18.692558 [INFO] mod_dialplan_xml.c:485 Processing 1000 <1000>->339XXXXXXXX in context default
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->unloop] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->tod_example] continue=true
Dialplan: sofia/internal/1000@192.168.0.2 Date/Time Match (PASS) [tod_example] break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 Action set(open=true)
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->holiday_example] continue=true
Dialplan: sofia/internal/1000@192.168.0.2 Date/TimeMatch (FAIL) [holiday_example] break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->global-intercept] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [global-intercept] destination_number(339XXXXXXXX) =~ /^886$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->group-intercept] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [group-intercept] destination_number(339XXXXXXXX) =~ /^\*8$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->intercept-ext] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [intercept-ext] destination_number(339XXXXXXXX) =~ /^\*\*(\d+)$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->redial] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [redial] destination_number(339XXXXXXXX) =~ /^(redial|870)$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->global] continue=true
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [global] ${sip_has_crypto}() =~ /^(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)$/ break=never
Dialplan: sofia/internal/1000@192.168.0.2 Absolute Condition [global]
Dialplan: sofia/internal/1000@192.168.0.2 Action hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid})
Dialplan: sofia/internal/1000@192.168.0.2 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
Dialplan: sofia/internal/1000@192.168.0.2 Action hash(insert/${domain_name}-last_dial/global/${uuid})
Dialplan: sofia/internal/1000@192.168.0.2 Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->snom-demo-2] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [snom-demo-2] destination_number(339XXXXXXXX) =~ /^9001$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->snom-demo-1] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [snom-demo-1] destination_number(339XXXXXXXX) =~ /^9000$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->eavesdrop] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [eavesdrop] destination_number(339XXXXXXXX) =~ /^88(\d{4})$|^\*0(.*)$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->eavesdrop] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [eavesdrop] destination_number(339XXXXXXXX) =~ /^779$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->call_return] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [call_return] destination_number(339XXXXXXXX) =~ /^\*69$|^869$|^lcr$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->del-group] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [del-group] destination_number(339XXXXXXXX) =~ /^80(\d{2})$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->add-group] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [add-group] destination_number(339XXXXXXXX) =~ /^81(\d{2})$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->call-group-simo] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [call-group-simo] destination_number(339XXXXXXXX) =~ /^82(\d{2})$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->call-group-order] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [call-group-order] destination_number(339XXXXXXXX) =~ /^83(\d{2})$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->extension-intercom] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [extension-intercom] destination_number(339XXXXXXXX) =~ /^8(10[01][0-9])$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->Local_Extension] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [Local_Extension] destination_number(339XXXXXXXX) =~ /^(10[01][0-9])$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->Local_Extension_Skinny] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [Local_Extension_Skinny] destination_number(339XXXXXXXX) =~ /^(11[01][0-9])$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->group_dial_sales] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [group_dial_sales] destination_number(339XXXXXXXX) =~ /^2000$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->group_dial_support] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [group_dial_support] destination_number(339XXXXXXXX) =~ /^2001$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->group_dial_billing] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [group_dial_billing] destination_number(339XXXXXXXX) =~ /^2002$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->operator] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [operator] destination_number(339XXXXXXXX) =~ /^(operator|0)$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->vmain] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [vmain] destination_number(339XXXXXXXX) =~ /^vmain$|^4000$|^\*98$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->sip_uri] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [sip_uri] destination_number(339XXXXXXXX) =~ /^sip:(.*)$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->nb_conferences] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [nb_conferences] destination_number(339XXXXXXXX) =~ /^(30\d{2})$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->wb_conferences] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [wb_conferences] destination_number(339XXXXXXXX) =~ /^(31\d{2})$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->uwb_conferences] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [uwb_conferences] destination_number(339XXXXXXXX) =~ /^(32\d{2})$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->cdquality_conferences] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [cdquality_conferences] destination_number(339XXXXXXXX) =~ /^(33\d{2})$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->freeswitch_public_conf_via_sip] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [freeswitch_public_conf_via_sip] destination_number(339XXXXXXXX) =~ /^9(888|8888|1616|3232)$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->mad_boss_intercom] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [mad_boss_intercom] destination_number(339XXXXXXXX) =~ /^0911$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->mad_boss_intercom] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [mad_boss_intercom] destination_number(339XXXXXXXX) =~ /^0912$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->mad_boss] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [mad_boss] destination_number(339XXXXXXXX) =~ /^0913$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->ivr_demo] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [ivr_demo] destination_number(339XXXXXXXX) =~ /^5000$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->dynamic_conference] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [dynamic_conference] destination_number(339XXXXXXXX) =~ /^5001$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->rtp_multicast_page] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [rtp_multicast_page] destination_number(339XXXXXXXX) =~ /^pagegroup$|^7243$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->park] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [park] destination_number(339XXXXXXXX) =~ /^5900$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->unpark] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [unpark] destination_number(339XXXXXXXX) =~ /^5901$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->valet_park] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [valet_park] destination_number(339XXXXXXXX) =~ /^(6000)$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->valet_park] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [valet_park] destination_number(339XXXXXXXX) =~ /^(60\d[1-9])$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->park] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (PASS) [park] source(mod_sofia) =~ /mod_sofia/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [park] destination_number(339XXXXXXXX) =~ /park\+(\d+)/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->unpark] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (PASS) [unpark] source(mod_sofia) =~ /mod_sofia/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [unpark] destination_number(339XXXXXXXX) =~ /^parking$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->park] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (PASS) [park] source(mod_sofia) =~ /mod_sofia/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [park] destination_number(339XXXXXXXX) =~ /callpark/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->unpark] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (PASS) [unpark] source(mod_sofia) =~ /mod_sofia/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [unpark] destination_number(339XXXXXXXX) =~ /pickup/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->wait] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [wait] destination_number(339XXXXXXXX) =~ /^wait$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->fax_receive] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [fax_receive] destination_number(339XXXXXXXX) =~ /^9178$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->fax_transmit] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [fax_transmit] destination_number(339XXXXXXXX) =~ /^9179$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->ringback_180] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [ringback_180] destination_number(339XXXXXXXX) =~ /^9180$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->ringback_183_uk_ring] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [ringback_183_uk_ring] destination_number(339XXXXXXXX) =~ /^9181$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->ringback_183_music_ring] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [ringback_183_music_ring] destination_number(339XXXXXXXX) =~ /^9182$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->ringback_post_answer_uk_ring] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [ringback_post_answer_uk_ring] destination_number(339XXXXXXXX) =~ /^9183$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->ringback_post_answer_music] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [ringback_post_answer_music] destination_number(339XXXXXXXX) =~ /^9184$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->ClueCon] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [ClueCon] destination_number(339XXXXXXXX) =~ /^9191$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->show_info] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [show_info] destination_number(339XXXXXXXX) =~ /^9192$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->video_record] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [video_record] destination_number(339XXXXXXXX) =~ /^9193$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->video_playback] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [video_playback] destination_number(339XXXXXXXX) =~ /^9194$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->delay_echo] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [delay_echo] destination_number(339XXXXXXXX) =~ /^9195$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->echo] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [echo] destination_number(339XXXXXXXX) =~ /^9196$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->milliwatt] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [milliwatt] destination_number(339XXXXXXXX) =~ /^9197$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->tone_stream] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [tone_stream] destination_number(339XXXXXXXX) =~ /^9198$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->zrtp_enrollement] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [zrtp_enrollement] destination_number(339XXXXXXXX) =~ /^9787$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->hold_music] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [hold_music] destination_number(339XXXXXXXX) =~ /^9664$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->from_pstn] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [from_pstn] destination_number(339XXXXXXXX) =~ /^0000$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->101] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [101] destination_number(339XXXXXXXX) =~ /^101$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->pizza_demo] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [pizza_demo] destination_number(339XXXXXXXX) =~ /^(pizza|74992)$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->gxw4104-fxo-local] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (PASS) [gxw4104-fxo-local] ${toll_allow}(domestic,international,local) =~ /local/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (PASS) [gxw4104-fxo-local] destination_number(339XXXXXXXX) =~ /^(\d{6,})$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 Action set(effective_caller_id_number=0321234567)
Dialplan: sofia/internal/1000@192.168.0.2 Action set(effective_caller_id_name=ThisIsMyCompany)
Dialplan: sofia/internal/1000@192.168.0.2 Action set(ignore_early_media=ring_ready)
Dialplan: sofia/internal/1000@192.168.0.2 Action set(ringback=${us-ring})
Dialplan: sofia/internal/1000@192.168.0.2 Action bridge(sofia/internal/gxw4104-fxo1/339XXXXXXXX@192.168.0.3:5060)
2012-03-26 11:31:18.692558 [DEBUG] switch_core_state_machine.c:154 (sofia/internal/1000@192.168.0.2) State Change CS_ROUTING -> CS_EXECUTE
2012-03-26 11:31:18.692558 [DEBUG] switch_core_session.c:1180 Send signal sofia/internal/1000@192.168.0.2 [BREAK]
2012-03-26 11:31:18.692558 [DEBUG] switch_core_state_machine.c:410 (sofia/internal/1000@192.168.0.2) State ROUTING going to sleep
2012-03-26 11:31:18.692558 [DEBUG] switch_core_state_machine.c:362 (sofia/internal/1000@192.168.0.2) Running State Change CS_EXECUTE
2012-03-26 11:31:18.692558 [DEBUG] switch_core_state_machine.c:417 (sofia/internal/1000@192.168.0.2) State EXECUTE
2012-03-26 11:31:18.692558 [DEBUG] mod_sofia.c:241 sofia/internal/1000@192.168.0.2 SOFIA EXECUTE
2012-03-26 11:31:18.692558 [DEBUG] switch_core_state_machine.c:192 sofia/internal/1000@192.168.0.2 Standard EXECUTE
EXECUTE sofia/internal/1000@192.168.0.2 set(open=true)
2012-03-26 11:31:18.692558 [DEBUG] mod_dptools.c:1281 sofia/internal/1000@192.168.0.2 SET [open]=[true]
EXECUTE sofia/internal/1000@192.168.0.2 hash(insert/192.168.0.2-spymap/1000/710da826-7726-11e1-bf72-e50b8b76e101)
EXECUTE sofia/internal/1000@192.168.0.2 hash(insert/192.168.0.2-last_dial/1000/339XXXXXXXX)
EXECUTE sofia/internal/1000@192.168.0.2 hash(insert/192.168.0.2-last_dial/global/710da826-7726-11e1-bf72-e50b8b76e101)
EXECUTE sofia/internal/1000@192.168.0.2 export(RFC2822_DATE=Mon, 26 Mar 2012 11:31:18 +0200)
2012-03-26 11:31:18.692558 [DEBUG] switch_channel.c:1093 EXPORT (export_vars) [RFC2822_DATE]=[Mon, 26 Mar 2012 11:31:18 +0200]
EXECUTE sofia/internal/1000@192.168.0.2 set(effective_caller_id_number=0321234567)
2012-03-26 11:31:18.692558 [DEBUG] mod_dptools.c:1281 sofia/internal/1000@192.168.0.2 SET [effective_caller_id_number]=[0321234567]
EXECUTE sofia/internal/1000@192.168.0.2 set(effective_caller_id_name=ThisIsMyCompany)
2012-03-26 11:31:18.692558 [DEBUG] mod_dptools.c:1281 sofia/internal/1000@192.168.0.2 SET [effective_caller_id_name]=[ThisIsMyCompany]
EXECUTE sofia/internal/1000@192.168.0.2 set(ignore_early_media=ring_ready)
2012-03-26 11:31:18.692558 [DEBUG] mod_dptools.c:1281 sofia/internal/1000@192.168.0.2 SET [ignore_early_media]=[ring_ready]
EXECUTE sofia/internal/1000@192.168.0.2 set(ringback=%(2000,4000,440,480))
2012-03-26 11:31:18.692558 [DEBUG] mod_dptools.c:1281 sofia/internal/1000@192.168.0.2 SET [ringback]=[%(2000,4000,440,480)]
EXECUTE sofia/internal/1000@192.168.0.2 bridge(sofia/internal/gxw4104-fxo1/339XXXXXXXX@192.168.0.3:5060)
2012-03-26 11:31:18.692558 [DEBUG] switch_channel.c:1047 sofia/internal/1000@192.168.0.2 EXPORTING[export_vars] [RFC2822_DATE]=[Mon, 26 Mar 2012 11:31:18 +0200] to event
2012-03-26 11:31:18.692558 [DEBUG] switch_ivr_originate.c:1884 Parsing global variables
2012-03-26 11:31:18.692558 [NOTICE] switch_channel.c:926 New Channel sofia/internal/gxw4104-fxo1/339XXXXXXXX@192.168.0.3:5060 [710f6738-7726-11e1-bf77-e50b8b76e101]
2012-03-26 11:31:18.692558 [DEBUG] mod_sofia.c:4691 (sofia/internal/gxw4104-fxo1/339XXXXXXXX@192.168.0.3:5060) State Change CS_NEW -> CS_INIT
2012-03-26 11:31:18.692558 [DEBUG] switch_core_session.c:1180 Send signal sofia/internal/gxw4104-fxo1/339XXXXXXXX@192.168.0.3:5060 [BREAK]
2012-03-26 11:31:18.712698 [DEBUG] switch_core_state_machine.c:362 (sofia/internal/gxw4104-fxo1/339XXXXXXXX@192.168.0.3:5060) Running State Change CS_INIT
2012-03-26 11:31:18.712698 [DEBUG] switch_core_state_machine.c:401 (sofia/internal/gxw4104-fxo1/339XXXXXXXX@192.168.0.3:5060) State INIT
2012-03-26 11:31:18.712698 [DEBUG] mod_sofia.c:85 sofia/internal/gxw4104-fxo1/339XXXXXXXX@192.168.0.3:5060 SOFIA INIT
send 1279 bytes to udp/[192.168.0.3]:5060 at 09:31:18.713245:
------------------------------------------------------------------------
INVITE sip:gxw4104-fxo1/339XXXXXXXX@192.168.0.3:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.0.2;rport;branch=z9hG4bK7Ume46yDFSK0K
Max-Forwards: 69
From: "ThisIsMyCompany" <sip:0321234567@192.168.0.2>;tag=reXaHtmyvm2eN
To: <sip:gxw4104-fxo1/339XXXXXXXX@192.168.0.3:5060>
Call-ID: 486dc943-f1c9-122f-5cb5-0800276bec7a
CSeq: 26041075 INVITE
Contact: <sip:mod_sofia@192.168.0.2:5060>
User-Agent: FreeSWITCH-mod_sofia/1.0.head-git-1aa9103 2012-03-01 15-58-48 -0600
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, precondition, path, replaces
Allow-Events: talk, hold, presence, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Privacy: none
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 311
X-FS-Support: update_display,send_info
P-Asserted-Identity: "ThisIsMyCompany" <sip:0321234567@192.168.0.2>
v=0
o=FreeSWITCH 1332736038 1332736039 IN IP4 192.168.0.2
s=FreeSWITCH
c=IN IP4 192.168.0.2
t=0 0
m=audio 18240 RTP/AVP 8 98 99 9 0 3 101 13
a=rtpmap:98 G7221/32000
a=fmtp:98 bitrate=48000
a=rtpmap:99 G7221/16000
a=fmtp:99 bitrate=32000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
------------------------------------------------------------------------
2012-03-26 11:31:18.712698 [DEBUG] switch_core_session.c:875 Send signal sofia/internal/gxw4104-fxo1/339XXXXXXXX@192.168.0.3:5060 [BREAK]
2012-03-26 11:31:18.712698 [DEBUG] mod_sofia.c:125 (sofia/internal/gxw4104-fxo1/339XXXXXXXX@192.168.0.3:5060) State Change CS_INIT -> CS_ROUTING
2012-03-26 11:31:18.712698 [DEBUG] switch_core_session.c:1180 Send signal sofia/internal/gxw4104-fxo1/339XXXXXXXX@192.168.0.3:5060 [BREAK]
2012-03-26 11:31:18.712698 [DEBUG] switch_core_state_machine.c:401 (sofia/internal/gxw4104-fxo1/339XXXXXXXX@192.168.0.3:5060) State INIT going to sleep
2012-03-26 11:31:18.712698 [DEBUG] switch_core_state_machine.c:362 (sofia/internal/gxw4104-fxo1/339XXXXXXXX@192.168.0.3:5060) Running State Change CS_ROUTING
2012-03-26 11:31:18.712698 [DEBUG] switch_channel.c:1886 (sofia/internal/gxw4104-fxo1/339XXXXXXXX@192.168.0.3:5060) Callstate Change DOWN -> RINGING
2012-03-26 11:31:18.712698 [DEBUG] switch_core_state_machine.c:410 (sofia/internal/gxw4104-fxo1/339XXXXXXXX@192.168.0.3:5060) State ROUTING
2012-03-26 11:31:18.712698 [DEBUG] mod_sofia.c:148 sofia/internal/gxw4104-fxo1/339XXXXXXXX@192.168.0.3:5060 SOFIA ROUTING
2012-03-26 11:31:18.712698 [DEBUG] switch_ivr_originate.c:66 (sofia/internal/gxw4104-fxo1/339XXXXXXXX@192.168.0.3:5060) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2012-03-26 11:31:18.712698 [DEBUG] switch_core_session.c:1180 Send signal sofia/internal/gxw4104-fxo1/339XXXXXXXX@192.168.0.3:5060 [BREAK]
2012-03-26 11:31:18.712698 [DEBUG] switch_core_state_machine.c:410 (sofia/internal/gxw4104-fxo1/339XXXXXXXX@192.168.0.3:5060) State ROUTING going to sleep
2012-03-26 11:31:18.712698 [DEBUG] switch_core_state_machine.c:362 (sofia/internal/gxw4104-fxo1/339XXXXXXXX@192.168.0.3:5060) Running State Change CS_CONSUME_MEDIA
2012-03-26 11:31:18.712698 [DEBUG] switch_core_state_machine.c:429 (sofia/internal/gxw4104-fxo1/339XXXXXXXX@192.168.0.3:5060) State CONSUME_MEDIA
2012-03-26 11:31:18.712698 [DEBUG] switch_core_state_machine.c:429 (sofia/internal/gxw4104-fxo1/339XXXXXXXX@192.168.0.3:5060) State CONSUME_MEDIA going to sleep
2012-03-26 11:31:18.712698 [DEBUG] sofia.c:5526 Channel sofia/internal/gxw4104-fxo1/339XXXXXXXX@192.168.0.3:5060 entering state [calling][0]
recv 355 bytes from udp/[192.168.0.3]:5060 at 09:31:18.717462:
------------------------------------------------------------------------
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.0.2;rport;branch=z9hG4bK7Ume46yDFSK0K
From: "ThisIsMyCompany" <sip:0321234567@192.168.0.2>;tag=reXaHtmyvm2eN
To: <sip:gxw4104-fxo1/339XXXXXXXX@192.168.0.3:5060>
Call-ID: 486dc943-f1c9-122f-5cb5-0800276bec7a
CSeq: 26041075 INVITE
User-Agent: Grandstream GXW4104 (HW 2.0, Ch:5) 1.3.4.9
Content-Length: 0
------------------------------------------------------------------------
recv 367 bytes from udp/[192.168.0.3]:5060 at 09:31:18.717980:
------------------------------------------------------------------------
SIP/2.0 403
Via: SIP/2.0/UDP 192.168.0.2;rport;branch=z9hG4bK7Ume46yDFSK0K
From: "ThisIsMyCompany" <sip:0321234567@192.168.0.2>;tag=reXaHtmyvm2eN
To: <sip:gxw4104-fxo1/339XXXXXXXX@192.168.0.3:5060>;tag=3ga2B2jmSSe6H
Call-ID: 486dc943-f1c9-122f-5cb5-0800276bec7a
CSeq: 26041075 INVITE
User-Agent: Grandstream GXW4104 (HW 2.0, Ch:8) 1.3.4.9
Content-Length: 0
------------------------------------------------------------------------
send 370 bytes to udp/[192.168.0.3]:5060 at 09:31:18.718065:
------------------------------------------------------------------------
ACK sip:gxw4104-fxo1/339XXXXXXXX@192.168.0.3:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.0.2;rport;branch=z9hG4bK7Ume46yDFSK0K
Max-Forwards: 69
From: "ThisIsMyCompany" <sip:0321234567@192.168.0.2>;tag=reXaHtmyvm2eN
To: <sip:gxw4104-fxo1/339XXXXXXXX@192.168.0.3:5060>;tag=3ga2B2jmSSe6H
Call-ID: 486dc943-f1c9-122f-5cb5-0800276bec7a
CSeq: 26041075 ACK
Content-Length: 0
------------------------------------------------------------------------
2012-03-26 11:31:18.712698 [DEBUG] switch_core_session.c:875 Send signal sofia/internal/gxw4104-fxo1/339XXXXXXXX@192.168.0.3:5060 [BREAK]
2012-03-26 11:31:18.712698 [DEBUG] switch_core_session.c:875 Send signal sofia/internal/gxw4104-fxo1/339XXXXXXXX@192.168.0.3:5060 [BREAK]
2012-03-26 11:31:18.712698 [DEBUG] switch_core_session.c:875 Send signal sofia/internal/gxw4104-fxo1/339XXXXXXXX@192.168.0.3:5060 [BREAK]
2012-03-26 11:31:18.712698 [DEBUG] sofia.c:5526 Channel sofia/internal/gxw4104-fxo1/339XXXXXXXX@192.168.0.3:5060 entering state [terminated][403]
2012-03-26 11:31:18.712698 [DEBUG] switch_channel.c:2848 (sofia/internal/gxw4104-fxo1/339XXXXXXXX@192.168.0.3:5060) Callstate Change RINGING -> HANGUP
2012-03-26 11:31:18.712698 [NOTICE] sofia.c:6293 Hangup sofia/internal/gxw4104-fxo1/339XXXXXXXX@192.168.0.3:5060 [CS_CONSUME_MEDIA] [CALL_REJECTED]
2012-03-26 11:31:18.712698 [DEBUG] switch_channel.c:2871 Send signal sofia/internal/gxw4104-fxo1/339XXXXXXXX@192.168.0.3:5060 [KILL]
2012-03-26 11:31:18.712698 [DEBUG] switch_core_session.c:1180 Send signal sofia/internal/gxw4104-fxo1/339XXXXXXXX@192.168.0.3:5060 [BREAK]
2012-03-26 11:31:18.712698 [DEBUG] switch_core_state_machine.c:362 (sofia/internal/gxw4104-fxo1/339XXXXXXXX@192.168.0.3:5060) Running State Change CS_HANGUP
2012-03-26 11:31:18.712698 [DEBUG] switch_ivr_originate.c:3364 Originate Resulted in Error Cause: 21 [CALL_REJECTED]
2012-03-26 11:31:18.712698 [INFO] mod_dptools.c:2922 Originate Failed. Cause: CALL_REJECTED
2012-03-26 11:31:18.712698 [DEBUG] switch_channel.c:2848 (sofia/internal/1000@192.168.0.2) Callstate Change RINGING -> HANGUP
2012-03-26 11:31:18.712698 [NOTICE] mod_dptools.c:3041 Hangup sofia/internal/1000@192.168.0.2 [CS_EXECUTE] [CALL_REJECTED]
2012-03-26 11:31:18.712698 [DEBUG] switch_channel.c:2871 Send signal sofia/internal/1000@192.168.0.2 [KILL]
2012-03-26 11:31:18.712698 [DEBUG] switch_core_session.c:1180 Send signal sofia/internal/1000@192.168.0.2 [BREAK]
2012-03-26 11:31:18.712698 [DEBUG] switch_core_session.c:2285 sofia/internal/1000@192.168.0.2 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2012-03-26 11:31:18.712698 [DEBUG] switch_core_state_machine.c:417 (sofia/internal/1000@192.168.0.2) State EXECUTE going to sleep
2012-03-26 11:31:18.712698 [DEBUG] switch_core_state_machine.c:362 (sofia/internal/1000@192.168.0.2) Running State Change CS_HANGUP
2012-03-26 11:31:18.712698 [DEBUG] switch_core_state_machine.c:602 (sofia/internal/1000@192.168.0.2) State HANGUP
2012-03-26 11:31:18.712698 [DEBUG] mod_sofia.c:463 sofia/internal/1000@192.168.0.2 Overriding SIP cause 603 with 403 from the other leg
2012-03-26 11:31:18.712698 [DEBUG] mod_sofia.c:469 Channel sofia/internal/1000@192.168.0.2 hanging up, cause: CALL_REJECTED
2012-03-26 11:31:18.712698 [DEBUG] mod_sofia.c:534 Responding to INVITE with: 403
2012-03-26 11:31:18.712698 [DEBUG] switch_core_state_machine.c:47 sofia/internal/1000@192.168.0.2 Standard HANGUP, cause: CALL_REJECTED
2012-03-26 11:31:18.712698 [DEBUG] switch_core_state_machine.c:602 (sofia/internal/1000@192.168.0.2) State HANGUP going to sleep
2012-03-26 11:31:18.712698 [DEBUG] switch_core_state_machine.c:393 (sofia/internal/1000@192.168.0.2) State Change CS_HANGUP -> CS_REPORTING
2012-03-26 11:31:18.712698 [DEBUG] switch_core_session.c:1180 Send signal sofia/internal/1000@192.168.0.2 [BREAK]
2012-03-26 11:31:18.712698 [DEBUG] switch_core_state_machine.c:362 (sofia/internal/1000@192.168.0.2) Running State Change CS_REPORTING
2012-03-26 11:31:18.712698 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/1000@192.168.0.2) State REPORTING
2012-03-26 11:31:18.712698 [DEBUG] switch_core_state_machine.c:79 sofia/internal/1000@192.168.0.2 Standard REPORTING, cause: CALL_REJECTED
2012-03-26 11:31:18.712698 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/1000@192.168.0.2) State REPORTING going to sleep
2012-03-26 11:31:18.712698 [DEBUG] switch_core_state_machine.c:387 (sofia/internal/1000@192.168.0.2) State Change CS_REPORTING -> CS_DESTROY
2012-03-26 11:31:18.712698 [DEBUG] switch_core_session.c:1180 Send signal sofia/internal/1000@192.168.0.2 [BREAK]
2012-03-26 11:31:18.712698 [DEBUG] switch_core_session.c:1380 Session 13 (sofia/internal/1000@192.168.0.2) Locked, Waiting on external entities
2012-03-26 11:31:18.712698 [NOTICE] switch_core_session.c:1398 Session 13 (sofia/internal/1000@192.168.0.2) Ended
2012-03-26 11:31:18.712698 [NOTICE] switch_core_session.c:1400 Close Channel sofia/internal/1000@192.168.0.2 [CS_DESTROY]
2012-03-26 11:31:18.712698 [DEBUG] switch_core_state_machine.c:491 (sofia/internal/1000@192.168.0.2) Callstate Change HANGUP -> DOWN
2012-03-26 11:31:18.712698 [DEBUG] switch_core_state_machine.c:494 (sofia/internal/1000@192.168.0.2) Running State Change CS_DESTROY
2012-03-26 11:31:18.712698 [DEBUG] switch_core_state_machine.c:504 (sofia/internal/1000@192.168.0.2) State DESTROY
2012-03-26 11:31:18.712698 [DEBUG] mod_sofia.c:374 sofia/internal/1000@192.168.0.2 SOFIA DESTROY
2012-03-26 11:31:18.712698 [DEBUG] switch_core_state_machine.c:86 sofia/internal/1000@192.168.0.2 Standard DESTROY
2012-03-26 11:31:18.712698 [DEBUG] switch_core_state_machine.c:504 (sofia/internal/1000@192.168.0.2) State DESTROY going to sleep
send 833 bytes to udp/[192.168.0.200]:5060 at 09:31:18.720584:
------------------------------------------------------------------------
SIP/2.0 403 Forbidden
Via: SIP/2.0/UDP 192.168.0.200:5060;branch=z9hG4bK00be621f9475e11195fa001e68586d6b;rport=5060
From: "1000" <sip:1000@192.168.0.2>;tag=645977894
To: <sip:339XXXXXXXX@192.168.0.2>;tag=Q53HFZ3tZBcvS
Call-ID: 00BE621F-9475-E111-95F8-001E68586D6B@192.168.0.200
CSeq: 6 INVITE
User-Agent: FreeSWITCH-mod_sofia/1.0.head-git-1aa9103 2012-03-01 15-58-48 -0600
Accept: application/sdp
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, precondition, path, replaces
Allow-Events: talk, hold, presence, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Reason: Q.850;cause=21;text="CALL_REJECTED"
Content-Length: 0
P-Asserted-Identity: "339XXXXXXXX" <sip:339XXXXXXXX@192.168.0.2>
------------------------------------------------------------------------
2012-03-26 11:31:18.712698 [DEBUG] switch_core_state_machine.c:602 (sofia/internal/gxw4104-fxo1/339XXXXXXXX@192.168.0.3:5060) State HANGUP
2012-03-26 11:31:18.712698 [DEBUG] mod_sofia.c:469 Channel sofia/internal/gxw4104-fxo1/339XXXXXXXX@192.168.0.3:5060 hanging up, cause: CALL_REJECTED
2012-03-26 11:31:18.712698 [DEBUG] switch_core_state_machine.c:47 sofia/internal/gxw4104-fxo1/339XXXXXXXX@192.168.0.3:5060 Standard HANGUP, cause: CALL_REJECTED
2012-03-26 11:31:18.712698 [DEBUG] switch_core_state_machine.c:602 (sofia/internal/gxw4104-fxo1/339XXXXXXXX@192.168.0.3:5060) State HANGUP going to sleep
2012-03-26 11:31:18.712698 [DEBUG] switch_core_state_machine.c:393 (sofia/internal/gxw4104-fxo1/339XXXXXXXX@192.168.0.3:5060) State Change CS_HANGUP -> CS_REPORTING
2012-03-26 11:31:18.712698 [DEBUG] switch_core_session.c:1180 Send signal sofia/internal/gxw4104-fxo1/339XXXXXXXX@192.168.0.3:5060 [BREAK]
2012-03-26 11:31:18.712698 [DEBUG] switch_core_state_machine.c:362 (sofia/internal/gxw4104-fxo1/339XXXXXXXX@192.168.0.3:5060) Running State Change CS_REPORTING
2012-03-26 11:31:18.712698 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/gxw4104-fxo1/339XXXXXXXX@192.168.0.3:5060) State REPORTING
2012-03-26 11:31:18.712698 [DEBUG] switch_core_state_machine.c:79 sofia/internal/gxw4104-fxo1/339XXXXXXXX@192.168.0.3:5060 Standard REPORTING, cause: CALL_REJECTED
2012-03-26 11:31:18.712698 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/gxw4104-fxo1/339XXXXXXXX@192.168.0.3:5060) State REPORTING going to sleep
recv 618 bytes from udp/[192.168.0.200]:5060 at 09:31:18.721757:
------------------------------------------------------------------------
ACK sip:339XXXXXXXX@192.168.0.2 SIP/2.0
Via: SIP/2.0/UDP 192.168.0.200:5060;branch=z9hG4bK00be621f9475e11195fa001e68586d6b;rport
From: "1000" <sip:1000@192.168.0.2>;tag=645977894
To: <sip:339XXXXXXXX@192.168.0.2>;tag=Q53HFZ3tZBcvS
Call-ID: 00BE621F-9475-E111-95F8-001E68586D6B@192.168.0.200
CSeq: 6 ACK
Proxy-Authorization: Digest username="1000", realm="192.168.0.2", nonce="710d05a6-7726-11e1-bf71-e50b8b76e101", uri="sip:339XXXXXXXX@192.168.0.2", response="7378107bc277149e3b6ef00c1c766a71", algorithm=MD5, cnonce="234abcc436e2667097e7fe6eia53e8dd", qop=auth, nc=00000001
Max-Forwards: 70
Content-Length: 0
------------------------------------------------------------------------
2012-03-26 11:31:18.712698 [DEBUG] switch_core_state_machine.c:387 (sofia/internal/gxw4104-fxo1/339XXXXXXXX@192.168.0.3:5060) State Change CS_REPORTING -> CS_DESTROY
2012-03-26 11:31:18.712698 [DEBUG] switch_core_session.c:1180 Send signal sofia/internal/gxw4104-fxo1/339XXXXXXXX@192.168.0.3:5060 [BREAK]
2012-03-26 11:31:18.712698 [DEBUG] switch_core_session.c:1380 Session 14 (sofia/internal/gxw4104-fxo1/339XXXXXXXX@192.168.0.3:5060) Locked, Waiting on external entities
2012-03-26 11:31:18.712698 [NOTICE] switch_core_session.c:1398 Session 14 (sofia/internal/gxw4104-fxo1/339XXXXXXXX@192.168.0.3:5060) Ended
2012-03-26 11:31:18.712698 [NOTICE] switch_core_session.c:1400 Close Channel sofia/internal/gxw4104-fxo1/339XXXXXXXX@192.168.0.3:5060 [CS_DESTROY]
2012-03-26 11:31:18.712698 [DEBUG] switch_core_state_machine.c:491 (sofia/internal/gxw4104-fxo1/339XXXXXXXX@192.168.0.3:5060) Callstate Change HANGUP -> DOWN
2012-03-26 11:31:18.712698 [DEBUG] switch_core_state_machine.c:494 (sofia/internal/gxw4104-fxo1/339XXXXXXXX@192.168.0.3:5060) Running State Change CS_DESTROY
2012-03-26 11:31:18.712698 [DEBUG] switch_core_state_machine.c:504 (sofia/internal/gxw4104-fxo1/339XXXXXXXX@192.168.0.3:5060) State DESTROY
2012-03-26 11:31:18.712698 [DEBUG] mod_sofia.c:374 sofia/internal/gxw4104-fxo1/339XXXXXXXX@192.168.0.3:5060 SOFIA DESTROY
2012-03-26 11:31:18.712698 [DEBUG] switch_core_state_machine.c:86 sofia/internal/gxw4104-fxo1/339XXXXXXXX@192.168.0.3:5060 Standard DESTROY
2012-03-26 11:31:18.712698 [DEBUG] switch_core_state_machine.c:504 (sofia/internal/gxw4104-fxo1/339XXXXXXXX@192.168.0.3:5060) State DESTROY going to sleep
freeswitch@internal>
Accursio,
Welcome to the FreeSWITCH Community!
Can you get us a siptrace?
fs_clisofia global siptrace on
...then pastebin the failing call to http://pastebin.freeswitch.org
Thanks!
-BDF
On Fri, Mar 23, 2012 at 5:47 AM, a.avona <a.avona@elios.net> wrote:
Hi, all
i have a GrandStream 4104 perfectly working (both incoming and outgoing
calls work great) with an asterisk pbx.
We are trying to change the asterisk with freeswitch, but i'm
exeperiencing problems in configuring freeswitch for outgoing calls,
incoming calls works well.
this is my configuration and what i obtain in fs_cli consolle
Can someone tell me where i'm wrong?
in sip_profile/internal i created a file 00_to_pstn.xml this way
<include>
<gateway name="gxw4104-fxo1">
<param name="username" value="1019"/>
<param name="realm" value="192.168.0.3"/>
<param name="sip-port" value="5060"/>
<param name="rtp_ip" value="192.168.0.3"/>
<param name="dtmf-type" value="rfc2833"/>
<param name="expire-seconds" value="600"/>
<param name="register" value="false"/>
<param name="caller-id-in-from" value="true"/>
</gateway>
</include>
in dialplan/default i created a file 00_to_pstn.xml this way
<include>
<extension name="gxw4104-fxo-local">
<condition field="${toll_allow}" expression="local"/>
<condition field="destination_number" expression="^(\d{6,})$">
<action application="set"
data="effective_caller_id_number=0321234567"/>
<action application="set"
data="effective_caller_id_name=ThisIsMyCompany"/>
<action application="set" data="ignore_early_media=ring_ready"/>
<action application="set" data="ringback=${us-ring}"/>
<action application="bridge"
data="sofia/internal/gxw4104-fxo1/$1@192.168.0.3:5060"/>
</condition>
</extension>
</include>
_________________________________________________________________
2012-03-23 10:38:43.937574 [DEBUG] sofia.c:7559 IP 192.168.0.200
Rejected by acl "domains". Falling back to Digest auth.
2012-03-23 10:38:43.937574 [DEBUG] sofia.c:7559 IP 192.168.0.200
Rejected by acl "domains". Falling back to Digest auth.
2012-03-23 10:38:43.937574 [NOTICE] switch_channel.c:926 New Channel
sofia/internal/1000@192.168.0.2 [fb33155c-74cb-11e1-9063-fb6a5f4599fb]
2012-03-23 10:38:43.937574 [DEBUG] sofia.c:5526 Channel
sofia/internal/1000@192.168.0.2 entering state [received][100]
2012-03-23 10:38:43.937574 [DEBUG] sofia.c:5537 Remote SDP:
v=0
o=- 935122583 0 IN IP4 192.168.0.200
s=SIPPER for PhonerLite
c=IN IP4 192.168.0.200
t=0 0
m=audio 5062 RTP/AVP 8 3 0 101
a=rtpmap:8 PCMA/8000
a=rtpmap:3 GSM/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
2012-03-23 10:38:43.937574 [DEBUG] sofia_glue.c:4874 Audio Codec Compare
[PCMA:8:8000:20:64000]/[G7221:115:32000:20:48000]
2012-03-23 10:38:43.937574 [DEBUG] sofia_glue.c:4874 Audio Codec Compare
[PCMA:8:8000:20:64000]/[G7221:107:16000:20:32000]
2012-03-23 10:38:43.937574 [DEBUG] sofia_glue.c:4874 Audio Codec Compare
[PCMA:8:8000:20:64000]/[G722:9:8000:20:64000]
2012-03-23 10:38:43.937574 [DEBUG] sofia_glue.c:4874 Audio Codec Compare
[PCMA:8:8000:20:64000]/[PCMU:0:8000:20:64000]
2012-03-23 10:38:43.937574 [DEBUG] sofia_glue.c:4874 Audio Codec Compare
[PCMA:8:8000:20:64000]/[PCMA:8:8000:20:64000]
2012-03-23 10:38:43.937574 [DEBUG] sofia_glue.c:2991 Set Codec
sofia/internal/1000@192.168.0.2 PCMA/8000 20 ms 160 samples 64000 bits
2012-03-23 10:38:43.937574 [DEBUG] switch_core_codec.c:111
sofia/internal/1000@192.168.0.2 Original read codec set to PCMA:8
2012-03-23 10:38:43.937574 [DEBUG] sofia_glue.c:4995 Set 2833 dtmf
send/recv payload to 101
2012-03-23 10:38:43.937574 [DEBUG] sofia.c:5749
(sofia/internal/1000@192.168.0.2) State Change CS_NEW -> CS_INIT
2012-03-23 10:38:43.937574 [DEBUG] switch_core_session.c:1180 Send
signal sofia/internal/1000@192.168.0.2 [BREAK]
2012-03-23 10:38:43.937574 [DEBUG] switch_core_state_machine.c:362
(sofia/internal/1000@192.168.0.2) Running State Change CS_INIT
2012-03-23 10:38:43.937574 [DEBUG] switch_core_state_machine.c:401
(sofia/internal/1000@192.168.0.2) State INIT
2012-03-23 10:38:43.937574 [DEBUG] mod_sofia.c:85
sofia/internal/1000@192.168.0.2 SOFIA INIT
2012-03-23 10:38:43.937574 [DEBUG] mod_sofia.c:125
(sofia/internal/1000@192.168.0.2) State Change CS_INIT -> CS_ROUTING
2012-03-23 10:38:43.937574 [DEBUG] switch_core_session.c:1180 Send
signal sofia/internal/1000@192.168.0.2 [BREAK]
2012-03-23 10:38:43.937574 [DEBUG] switch_core_state_machine.c:401
(sofia/internal/1000@192.168.0.2) State INIT going to sleep
2012-03-23 10:38:43.937574 [DEBUG] switch_core_state_machine.c:362
(sofia/internal/1000@192.168.0.2) Running State Change CS_ROUTING
2012-03-23 10:38:43.937574 [DEBUG] switch_channel.c:1886
(sofia/internal/1000@192.168.0.2) Callstate Change DOWN -> RINGING
2012-03-23 10:38:43.937574 [DEBUG] switch_core_state_machine.c:410
(sofia/internal/1000@192.168.0.2) State ROUTING
2012-03-23 10:38:43.937574 [DEBUG] mod_sofia.c:148
sofia/internal/1000@192.168.0.2 SOFIA ROUTING
2012-03-23 10:38:43.937574 [DEBUG] switch_core_state_machine.c:104
sofia/internal/1000@192.168.0.2 Standard ROUTING
2012-03-23 10:38:43.937574 [INFO] mod_dialplan_xml.c:485 Processing 1000
<1000>->339XXXXXXX in context default
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->unloop]
continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (PASS) [unloop]
${unroll_loops}(true) =~ /^true$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [unloop]
${sip_looped_call}() =~ /^true$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->tod_example]
continue=true
Dialplan: sofia/internal/1000@192.168.0.2 Date/Time Match (PASS)
[tod_example] break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 Action set(open=true)
Dialplan: sofia/internal/1000@192.168.0.2 parsing
[default->holiday_example] continue=true
Dialplan: sofia/internal/1000@192.168.0.2 Date/TimeMatch (FAIL)
[holiday_example] break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing
[default->global-intercept] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL)
[global-intercept] destination_number(339XXXXXXX) =~ /^886$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing
[default->group-intercept] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [group-intercept]
destination_number(339XXXXXXX) =~ /^\*8$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing
[default->intercept-ext] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [intercept-ext]
destination_number(339XXXXXXX) =~ /^\*\*(\d+)$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->redial]
continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [redial]
destination_number(339XXXXXXX) =~ /^(redial|870)$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->global]
continue=true
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [global]
${call_debug}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [global]
${sip_has_crypto}() =~
/^(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)$/ break=never
Dialplan: sofia/internal/1000@192.168.0.2 Absolute Condition [global]
Dialplan: sofia/internal/1000@192.168.0.2 Action
hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid})
Dialplan: sofia/internal/1000@192.168.0.2 Action
hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
Dialplan: sofia/internal/1000@192.168.0.2 Action
hash(insert/${domain_name}-last_dial/global/${uuid})
Dialplan: sofia/internal/1000@192.168.0.2 Action
export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->snom-demo-2]
continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [snom-demo-2]
destination_number(339XXXXXXX) =~ /^9001$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->snom-demo-1]
continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [snom-demo-1]
destination_number(339XXXXXXX) =~ /^9000$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->eavesdrop]
continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [eavesdrop]
destination_number(339XXXXXXX) =~ /^88(\d{4})$|^\*0(.*)$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->eavesdrop]
continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [eavesdrop]
destination_number(339XXXXXXX) =~ /^779$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->call_return]
continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [call_return]
destination_number(339XXXXXXX) =~ /^\*69$|^869$|^lcr$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->del-group]
continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [del-group]
destination_number(339XXXXXXX) =~ /^80(\d{2})$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->add-group]
continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [add-group]
destination_number(339XXXXXXX) =~ /^81(\d{2})$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing
[default->call-group-simo] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [call-group-simo]
destination_number(339XXXXXXX) =~ /^82(\d{2})$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing
[default->call-group-order] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL)
[call-group-order] destination_number(339XXXXXXX) =~ /^83(\d{2})$/
break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing
[default->extension-intercom] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL)
[extension-intercom] destination_number(339XXXXXXX) =~
/^8(10[01][0-9])$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing
[default->Local_Extension] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [Local_Extension]
destination_number(339XXXXXXX) =~ /^(10[01][0-9])$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing
[default->Local_Extension_Skinny] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL)
[Local_Extension_Skinny] destination_number(339XXXXXXX) =~
/^(11[01][0-9])$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing
[default->group_dial_sales] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL)
[group_dial_sales] destination_number(339XXXXXXX) =~ /^2000$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing
[default->group_dial_support] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL)
[group_dial_support] destination_number(339XXXXXXX) =~ /^2001$/
break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing
[default->group_dial_billing] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL)
[group_dial_billing] destination_number(339XXXXXXX) =~ /^2002$/
break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->operator]
continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [operator]
destination_number(339XXXXXXX) =~ /^(operator|0)$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->vmain]
continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [vmain]
destination_number(339XXXXXXX) =~ /^vmain$|^4000$|^\*98$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->sip_uri]
continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [sip_uri]
destination_number(339XXXXXXX) =~ /^sip:(.*)$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing
[default->nb_conferences] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [nb_conferences]
destination_number(339XXXXXXX) =~ /^(30\d{2})$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing
[default->wb_conferences] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [wb_conferences]
destination_number(339XXXXXXX) =~ /^(31\d{2})$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing
[default->uwb_conferences] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [uwb_conferences]
destination_number(339XXXXXXX) =~ /^(32\d{2})$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing
[default->cdquality_conferences] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL)
[cdquality_conferences] destination_number(339XXXXXXX) =~ /^(33\d{2})$/
break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing
[default->freeswitch_public_conf_via_sip] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL)
[freeswitch_public_conf_via_sip] destination_number(339XXXXXXX) =~
/^9(888|8888|1616|3232)$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing
[default->mad_boss_intercom] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL)
[mad_boss_intercom] destination_number(339XXXXXXX) =~ /^0911$/
break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing
[default->mad_boss_intercom] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL)
[mad_boss_intercom] destination_number(339XXXXXXX) =~ /^0912$/
break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->mad_boss]
continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [mad_boss]
destination_number(339XXXXXXX) =~ /^0913$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->ivr_demo]
continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [ivr_demo]
destination_number(339XXXXXXX) =~ /^5000$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing
[default->dynamic_conference] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL)
[dynamic_conference] destination_number(339XXXXXXX) =~ /^5001$/
break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing
[default->rtp_multicast_page] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL)
[rtp_multicast_page] destination_number(339XXXXXXX) =~
/^pagegroup$|^7243$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->park]
continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [park]
destination_number(339XXXXXXX) =~ /^5900$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->unpark]
continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [unpark]
destination_number(339XXXXXXX) =~ /^5901$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->valet_park]
continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [valet_park]
destination_number(339XXXXXXX) =~ /^(6000)$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->valet_park]
continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [valet_park]
destination_number(339XXXXXXX) =~ /^(60\d[1-9])$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->park]
continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (PASS) [park]
source(mod_sofia) =~ /mod_sofia/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [park]
destination_number(339XXXXXXX) =~ /park\+(\d+)/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->unpark]
continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (PASS) [unpark]
source(mod_sofia) =~ /mod_sofia/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [unpark]
destination_number(339XXXXXXX) =~ /^parking$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->park]
continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (PASS) [park]
source(mod_sofia) =~ /mod_sofia/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [park]
destination_number(339XXXXXXX) =~ /callpark/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->unpark]
continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (PASS) [unpark]
source(mod_sofia) =~ /mod_sofia/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [unpark]
destination_number(339XXXXXXX) =~ /pickup/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->wait]
continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [wait]
destination_number(339XXXXXXX) =~ /^wait$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->fax_receive]
continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [fax_receive]
destination_number(339XXXXXXX) =~ /^9178$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing
[default->fax_transmit] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [fax_transmit]
destination_number(339XXXXXXX) =~ /^9179$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing
[default->ringback_180] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [ringback_180]
destination_number(339XXXXXXX) =~ /^9180$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing
[default->ringback_183_uk_ring] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL)
[ringback_183_uk_ring] destination_number(339XXXXXXX) =~ /^9181$/
break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing
[default->ringback_183_music_ring] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL)
[ringback_183_music_ring] destination_number(339XXXXXXX) =~ /^9182$/
break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing
[default->ringback_post_answer_uk_ring] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL)
[ringback_post_answer_uk_ring] destination_number(339XXXXXXX) =~
/^9183$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing
[default->ringback_post_answer_music] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL)
[ringback_post_answer_music] destination_number(339XXXXXXX) =~ /^9184$/
break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->ClueCon]
continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [ClueCon]
destination_number(339XXXXXXX) =~ /^9191$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->show_info]
continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [show_info]
destination_number(339XXXXXXX) =~ /^9192$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing
[default->video_record] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [video_record]
destination_number(339XXXXXXX) =~ /^9193$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing
[default->video_playback] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [video_playback]
destination_number(339XXXXXXX) =~ /^9194$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->delay_echo]
continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [delay_echo]
destination_number(339XXXXXXX) =~ /^9195$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->echo]
continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [echo]
destination_number(339XXXXXXX) =~ /^9196$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->milliwatt]
continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [milliwatt]
destination_number(339XXXXXXX) =~ /^9197$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->tone_stream]
continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [tone_stream]
destination_number(339XXXXXXX) =~ /^9198$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing
[default->zrtp_enrollement] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL)
[zrtp_enrollement] destination_number(339XXXXXXX) =~ /^9787$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->hold_music]
continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [hold_music]
destination_number(339XXXXXXX) =~ /^9664$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->from_pstn]
continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [from_pstn]
destination_number(339XXXXXXX) =~ /^0000$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->101]
continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [101]
destination_number(339XXXXXXX) =~ /^101$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing [default->pizza_demo]
continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (FAIL) [pizza_demo]
destination_number(339XXXXXXX) =~ /^(pizza|74992)$/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 parsing
[default->gxw4104-fxo-local] continue=false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (PASS)
[gxw4104-fxo-local] ${toll_allow}(domestic,international,local) =~
/local/ break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 Regex (PASS)
[gxw4104-fxo-local] destination_number(339XXXXXXX) =~ /^(\d{6,})$/
break=on-false
Dialplan: sofia/internal/1000@192.168.0.2 Action
set(effective_caller_id_number=0321234567)
Dialplan: sofia/internal/1000@192.168.0.2 Action
set(effective_caller_id_name=ThisIsMyCompany)
Dialplan: sofia/internal/1000@192.168.0.2 Action
set(ignore_early_media=ring_ready)
Dialplan: sofia/internal/1000@192.168.0.2 Action set(ringback=${us-ring})
Dialplan: sofia/internal/1000@192.168.0.2 Action
bridge(sofia/internal/gxw4104-fxo1/339XXXXXXX@192.168.0.3:5060)
2012-03-23 10:38:43.937574 [DEBUG] switch_core_state_machine.c:154
(sofia/internal/1000@192.168.0.2) State Change CS_ROUTING -> CS_EXECUTE
2012-03-23 10:38:43.937574 [DEBUG] switch_core_session.c:1180 Send
signal sofia/internal/1000@192.168.0.2 [BREAK]
2012-03-23 10:38:43.937574 [DEBUG] switch_core_state_machine.c:410
(sofia/internal/1000@192.168.0.2) State ROUTING going to sleep
2012-03-23 10:38:43.937574 [DEBUG] switch_core_state_machine.c:362
(sofia/internal/1000@192.168.0.2) Running State Change CS_EXECUTE
2012-03-23 10:38:43.937574 [DEBUG] switch_core_state_machine.c:417
(sofia/internal/1000@192.168.0.2) State EXECUTE
2012-03-23 10:38:43.937574 [DEBUG] mod_sofia.c:241
sofia/internal/1000@192.168.0.2 SOFIA EXECUTE
2012-03-23 10:38:43.937574 [DEBUG] switch_core_state_machine.c:192
sofia/internal/1000@192.168.0.2 Standard EXECUTE
EXECUTE sofia/internal/1000@192.168.0.2 set(open=true)
2012-03-23 10:38:43.937574 [DEBUG] mod_dptools.c:1281
sofia/internal/1000@192.168.0.2 SET [open]=[true]
EXECUTE sofia/internal/1000@192.168.0.2
hash(insert/192.168.0.2-spymap/1000/fb33155c-74cb-11e1-9063-fb6a5f4599fb)
EXECUTE sofia/internal/1000@192.168.0.2
hash(insert/192.168.0.2-last_dial/1000/339XXXXXXX)
EXECUTE sofia/internal/1000@192.168.0.2
hash(insert/192.168.0.2-last_dial/global/fb33155c-74cb-11e1-9063-fb6a5f4599fb)
EXECUTE sofia/internal/1000@192.168.0.2 export(RFC2822_DATE=Fri, 23 Mar
2012 10:38:43 +0100)
2012-03-23 10:38:43.937574 [DEBUG] switch_channel.c:1093 EXPORT
(export_vars) [RFC2822_DATE]=[Fri, 23 Mar 2012 10:38:43 +0100]
EXECUTE sofia/internal/1000@192.168.0.2
set(effective_caller_id_number=0321234567)
2012-03-23 10:38:43.937574 [DEBUG] mod_dptools.c:1281
sofia/internal/1000@192.168.0.2 SET
[effective_caller_id_number]=[0321234567]
EXECUTE sofia/internal/1000@192.168.0.2
set(effective_caller_id_name=ThisIsMyCompany)
2012-03-23 10:38:43.937574 [DEBUG] mod_dptools.c:1281
sofia/internal/1000@192.168.0.2 SET
[effective_caller_id_name]=[ThisIsMyCompany]
EXECUTE sofia/internal/1000@192.168.0.2 set(ignore_early_media=ring_ready)
2012-03-23 10:38:43.937574 [DEBUG] mod_dptools.c:1281
sofia/internal/1000@192.168.0.2 SET [ignore_early_media]=[ring_ready]
EXECUTE sofia/internal/1000@192.168.0.2 set(ringback=%(2000,4000,440,480))
2012-03-23 10:38:43.937574 [DEBUG] mod_dptools.c:1281
sofia/internal/1000@192.168.0.2 SET [ringback]=[%(2000,4000,440,480)]
EXECUTE sofia/internal/1000@192.168.0.2
bridge(sofia/internal/gxw4104-fxo1/339XXXXXXX@192.168.0.3:5060)
2012-03-23 10:38:43.937574 [DEBUG] switch_channel.c:1047
sofia/internal/1000@192.168.0.2 EXPORTING[export_vars]
[RFC2822_DATE]=[Fri, 23 Mar 2012 10:38:43 +0100] to event
2012-03-23 10:38:43.937574 [DEBUG] switch_ivr_originate.c:1884 Parsing
global variables
2012-03-23 10:38:43.937574 [NOTICE] switch_channel.c:926 New Channel
sofia/internal/gxw4104-fxo1/339XXXXXXX@192.168.0.3:5060
[fb34cf5a-74cb-11e1-9068-fb6a5f4599fb]
2012-03-23 10:38:43.937574 [DEBUG] mod_sofia.c:4691
(sofia/internal/gxw4104-fxo1/339XXXXXXX@192.168.0.3:5060) State Change
CS_NEW -> CS_INIT
2012-03-23 10:38:43.937574 [DEBUG] switch_core_session.c:1180 Send
signal sofia/internal/gxw4104-fxo1/339XXXXXXX@192.168.0.3:5060 [BREAK]
2012-03-23 10:38:43.937574 [DEBUG] switch_core_state_machine.c:362
(sofia/internal/gxw4104-fxo1/339XXXXXXX@192.168.0.3:5060) Running State
Change CS_INIT
2012-03-23 10:38:43.937574 [DEBUG] switch_core_state_machine.c:401
(sofia/internal/gxw4104-fxo1/339XXXXXXX@192.168.0.3:5060) State INIT
2012-03-23 10:38:43.937574 [DEBUG] mod_sofia.c:85
sofia/internal/gxw4104-fxo1/339XXXXXXX@192.168.0.3:5060 SOFIA INIT
2012-03-23 10:38:43.937574 [DEBUG] switch_core_session.c:875 Send signal
sofia/internal/gxw4104-fxo1/339XXXXXXX@192.168.0.3:5060 [BREAK]
2012-03-23 10:38:43.937574 [DEBUG] mod_sofia.c:125
(sofia/internal/gxw4104-fxo1/339XXXXXXX@192.168.0.3:5060) State Change
CS_INIT -> CS_ROUTING
2012-03-23 10:38:43.937574 [DEBUG] switch_core_session.c:1180 Send
signal sofia/internal/gxw4104-fxo1/339XXXXXXX@192.168.0.3:5060 [BREAK]
2012-03-23 10:38:43.937574 [DEBUG] switch_core_state_machine.c:401
(sofia/internal/gxw4104-fxo1/339XXXXXXX@192.168.0.3:5060) State INIT
going to sleep
2012-03-23 10:38:43.937574 [DEBUG] switch_core_state_machine.c:362
(sofia/internal/gxw4104-fxo1/339XXXXXXX@192.168.0.3:5060) Running State
Change CS_ROUTING
2012-03-23 10:38:43.937574 [DEBUG] switch_channel.c:1886
(sofia/internal/gxw4104-fxo1/339XXXXXXX@192.168.0.3:5060) Callstate
Change DOWN -> RINGING
2012-03-23 10:38:43.957579 [DEBUG] switch_core_state_machine.c:410
(sofia/internal/gxw4104-fxo1/339XXXXXXX@192.168.0.3:5060) State ROUTING
2012-03-23 10:38:43.957579 [DEBUG] mod_sofia.c:148
sofia/internal/gxw4104-fxo1/339XXXXXXX@192.168.0.3:5060 SOFIA ROUTING
2012-03-23 10:38:43.957579 [DEBUG] switch_ivr_originate.c:66
(sofia/internal/gxw4104-fxo1/339XXXXXXX@192.168.0.3:5060) State Change
CS_ROUTING -> CS_CONSUME_MEDIA
2012-03-23 10:38:43.957579 [DEBUG] switch_core_session.c:1180 Send
signal sofia/internal/gxw4104-fxo1/339XXXXXXX@192.168.0.3:5060 [BREAK]
2012-03-23 10:38:43.957579 [DEBUG] switch_core_state_machine.c:410
(sofia/internal/gxw4104-fxo1/339XXXXXXX@192.168.0.3:5060) State ROUTING
going to sleep
2012-03-23 10:38:43.957579 [DEBUG] switch_core_state_machine.c:362
(sofia/internal/gxw4104-fxo1/339XXXXXXX@192.168.0.3:5060) Running State
Change CS_CONSUME_MEDIA
2012-03-23 10:38:43.957579 [DEBUG] switch_core_state_machine.c:429
(sofia/internal/gxw4104-fxo1/339XXXXXXX@192.168.0.3:5060) State
CONSUME_MEDIA
2012-03-23 10:38:43.957579 [DEBUG] switch_core_state_machine.c:429
(sofia/internal/gxw4104-fxo1/339XXXXXXX@192.168.0.3:5060) State
CONSUME_MEDIA going to sleep
2012-03-23 10:38:43.957579 [DEBUG] sofia.c:5526 Channel
sofia/internal/gxw4104-fxo1/339XXXXXXX@192.168.0.3:5060 entering state
[calling][0]
2012-03-23 10:38:43.957579 [DEBUG] switch_core_session.c:875 Send signal
sofia/internal/gxw4104-fxo1/339XXXXXXX@192.168.0.3:5060 [BREAK]
2012-03-23 10:38:43.957579 [DEBUG] switch_core_session.c:875 Send signal
sofia/internal/gxw4104-fxo1/339XXXXXXX@192.168.0.3:5060 [BREAK]
2012-03-23 10:38:43.957579 [DEBUG] switch_core_session.c:875 Send signal
sofia/internal/gxw4104-fxo1/339XXXXXXX@192.168.0.3:5060 [BREAK]
2012-03-23 10:38:43.957579 [DEBUG] sofia.c:5526 Channel
sofia/internal/gxw4104-fxo1/339XXXXXXX@192.168.0.3:5060 entering state
[terminated][403]
2012-03-23 10:38:43.957579 [DEBUG] switch_channel.c:2848
(sofia/internal/gxw4104-fxo1/339XXXXXXX@192.168.0.3:5060) Callstate
Change RINGING -> HANGUP
2012-03-23 10:38:43.957579 [NOTICE] sofia.c:6293 Hangup
sofia/internal/gxw4104-fxo1/339XXXXXXX@192.168.0.3:5060
[CS_CONSUME_MEDIA] [CALL_REJECTED]
2012-03-23 10:38:43.957579 [DEBUG] switch_channel.c:2871 Send signal
sofia/internal/gxw4104-fxo1/339XXXXXXX@192.168.0.3:5060 [KILL]
2012-03-23 10:38:43.957579 [DEBUG] switch_core_session.c:1180 Send
signal sofia/internal/gxw4104-fxo1/339XXXXXXX@192.168.0.3:5060 [BREAK]
2012-03-23 10:38:43.957579 [DEBUG] switch_core_state_machine.c:362
(sofia/internal/gxw4104-fxo1/339XXXXXXX@192.168.0.3:5060) Running State
Change CS_HANGUP
2012-03-23 10:38:43.957579 [DEBUG] switch_core_state_machine.c:602
(sofia/internal/gxw4104-fxo1/339XXXXXXX@192.168.0.3:5060) State HANGUP
2012-03-23 10:38:43.957579 [DEBUG] mod_sofia.c:469 Channel
sofia/internal/gxw4104-fxo1/339XXXXXXX@192.168.0.3:5060 hanging up,
cause: CALL_REJECTED
2012-03-23 10:38:43.957579 [DEBUG] switch_core_state_machine.c:47
sofia/internal/gxw4104-fxo1/339XXXXXXX@192.168.0.3:5060 Standard HANGUP,
cause: CALL_REJECTED
2012-03-23 10:38:43.957579 [DEBUG] switch_core_state_machine.c:602
(sofia/internal/gxw4104-fxo1/339XXXXXXX@192.168.0.3:5060) State HANGUP
going to sleep
2012-03-23 10:38:43.957579 [DEBUG] switch_core_state_machine.c:393
(sofia/internal/gxw4104-fxo1/339XXXXXXX@192.168.0.3:5060) State Change
CS_HANGUP -> CS_REPORTING
2012-03-23 10:38:43.957579 [DEBUG] switch_core_session.c:1180 Send
signal sofia/internal/gxw4104-fxo1/339XXXXXXX@192.168.0.3:5060 [BREAK]
2012-03-23 10:38:43.957579 [DEBUG] switch_core_state_machine.c:362
(sofia/internal/gxw4104-fxo1/339XXXXXXX@192.168.0.3:5060) Running State
Change CS_REPORTING
2012-03-23 10:38:43.957579 [DEBUG] switch_core_state_machine.c:662
(sofia/internal/gxw4104-fxo1/339XXXXXXX@192.168.0.3:5060) State REPORTING
2012-03-23 10:38:43.957579 [DEBUG] switch_core_state_machine.c:79
sofia/internal/gxw4104-fxo1/339XXXXXXX@192.168.0.3:5060 Standard
REPORTING, cause: CALL_REJECTED
2012-03-23 10:38:43.957579 [DEBUG] switch_core_state_machine.c:662
(sofia/internal/gxw4104-fxo1/339XXXXXXX@192.168.0.3:5060) State
REPORTING going to sleep
2012-03-23 10:38:43.957579 [DEBUG] switch_core_state_machine.c:387
(sofia/internal/gxw4104-fxo1/339XXXXXXX@192.168.0.3:5060) State Change
CS_REPORTING -> CS_DESTROY
2012-03-23 10:38:43.957579 [DEBUG] switch_ivr_originate.c:3364 Originate
Resulted in Error Cause: 21 [CALL_REJECTED]
2012-03-23 10:38:43.957579 [INFO] mod_dptools.c:2922 Originate Failed.
Cause: CALL_REJECTED
2012-03-23 10:38:43.957579 [DEBUG] switch_channel.c:2848
(sofia/internal/1000@192.168.0.2) Callstate Change RINGING -> HANGUP
2012-03-23 10:38:43.957579 [NOTICE] mod_dptools.c:3041 Hangup
sofia/internal/1000@192.168.0.2 [CS_EXECUTE] [CALL_REJECTED]
2012-03-23 10:38:43.957579 [DEBUG] switch_channel.c:2871 Send signal
sofia/internal/1000@192.168.0.2 [KILL]
2012-03-23 10:38:43.957579 [DEBUG] switch_core_session.c:1180 Send
signal sofia/internal/1000@192.168.0.2 [BREAK]
2012-03-23 10:38:43.957579 [DEBUG] switch_core_session.c:2285
sofia/internal/1000@192.168.0.2 skip receive message
[APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2012-03-23 10:38:43.957579 [DEBUG] switch_core_state_machine.c:417
(sofia/internal/1000@192.168.0.2) State EXECUTE going to sleep
2012-03-23 10:38:43.957579 [DEBUG] switch_core_state_machine.c:362
(sofia/internal/1000@192.168.0.2) Running State Change CS_HANGUP
2012-03-23 10:38:43.957579 [DEBUG] switch_core_state_machine.c:602
(sofia/internal/1000@192.168.0.2) State HANGUP
2012-03-23 10:38:43.957579 [DEBUG] mod_sofia.c:463
sofia/internal/1000@192.168.0.2 Overriding SIP cause 603 with 403 from
the other leg
2012-03-23 10:38:43.957579 [DEBUG] mod_sofia.c:469 Channel
sofia/internal/1000@192.168.0.2 hanging up, cause: CALL_REJECTED
2012-03-23 10:38:43.957579 [DEBUG] mod_sofia.c:534 Responding to INVITE
with: 403
2012-03-23 10:38:43.957579 [DEBUG] switch_core_state_machine.c:47
sofia/internal/1000@192.168.0.2 Standard HANGUP, cause: CALL_REJECTED
2012-03-23 10:38:43.957579 [DEBUG] switch_core_state_machine.c:602
(sofia/internal/1000@192.168.0.2) State HANGUP going to sleep
2012-03-23 10:38:43.957579 [DEBUG] switch_core_state_machine.c:393
(sofia/internal/1000@192.168.0.2) State Change CS_HANGUP -> CS_REPORTING
2012-03-23 10:38:43.957579 [DEBUG] switch_core_session.c:1180 Send
signal sofia/internal/1000@192.168.0.2 [BREAK]
2012-03-23 10:38:43.957579 [DEBUG] switch_core_state_machine.c:362
(sofia/internal/1000@192.168.0.2) Running State Change CS_REPORTING
2012-03-23 10:38:43.957579 [DEBUG] switch_core_state_machine.c:662
(sofia/internal/1000@192.168.0.2) State REPORTING
2012-03-23 10:38:43.957579 [DEBUG] switch_core_state_machine.c:79
sofia/internal/1000@192.168.0.2 Standard REPORTING, cause: CALL_REJECTED
2012-03-23 10:38:43.957579 [DEBUG] switch_core_state_machine.c:662
(sofia/internal/1000@192.168.0.2) State REPORTING going to sleep
2012-03-23 10:38:43.957579 [DEBUG] switch_core_state_machine.c:387
(sofia/internal/1000@192.168.0.2) State Change CS_REPORTING -> CS_DESTROY
2012-03-23 10:38:43.957579 [DEBUG] switch_core_session.c:1180 Send
signal sofia/internal/1000@192.168.0.2 [BREAK]
2012-03-23 10:38:43.957579 [DEBUG] switch_core_session.c:1380 Session 13
(sofia/internal/1000@192.168.0.2) Locked, Waiting on external entities
2012-03-23 10:38:43.957579 [NOTICE] switch_core_session.c:1398 Session
13 (sofia/internal/1000@192.168.0.2) Ended
2012-03-23 10:38:43.957579 [NOTICE] switch_core_session.c:1400 Close
Channel sofia/internal/1000@192.168.0.2 [CS_DESTROY]
2012-03-23 10:38:43.957579 [DEBUG] switch_core_state_machine.c:491
(sofia/internal/1000@192.168.0.2) Callstate Change HANGUP -> DOWN
2012-03-23 10:38:43.957579 [DEBUG] switch_core_state_machine.c:494
(sofia/internal/1000@192.168.0.2) Running State Change CS_DESTROY
2012-03-23 10:38:43.957579 [DEBUG] switch_core_state_machine.c:504
(sofia/internal/1000@192.168.0.2) State DESTROY
2012-03-23 10:38:43.957579 [DEBUG] mod_sofia.c:374
sofia/internal/1000@192.168.0.2 SOFIA DESTROY
2012-03-23 10:38:43.957579 [DEBUG] switch_core_state_machine.c:86
sofia/internal/1000@192.168.0.2 Standard DESTROY
2012-03-23 10:38:43.957579 [DEBUG] switch_core_state_machine.c:504
(sofia/internal/1000@192.168.0.2) State DESTROY going to sleep
2012-03-23 10:38:43.957579 [DEBUG] switch_core_session.c:1180 Send
signal sofia/internal/gxw4104-fxo1/339XXXXXXX@192.168.0.3:5060 [BREAK]
2012-03-23 10:38:43.957579 [DEBUG] switch_core_session.c:1380 Session 14
(sofia/internal/gxw4104-fxo1/339XXXXXXX@192.168.0.3:5060) Locked,
Waiting on external entities
2012-03-23 10:38:43.957579 [NOTICE] switch_core_session.c:1398 Session
14 (sofia/internal/gxw4104-fxo1/339XXXXXXX@192.168.0.3:5060) Ended
2012-03-23 10:38:43.957579 [NOTICE] switch_core_session.c:1400 Close
Channel sofia/internal/gxw4104-fxo1/339XXXXXXX@192.168.0.3:5060 [CS_DESTROY]
2012-03-23 10:38:43.957579 [DEBUG] switch_core_state_machine.c:491
(sofia/internal/gxw4104-fxo1/339XXXXXXX@192.168.0.3:5060) Callstate
Change HANGUP -> DOWN
2012-03-23 10:38:43.957579 [DEBUG] switch_core_state_machine.c:494
(sofia/internal/gxw4104-fxo1/339XXXXXXX@192.168.0.3:5060) Running State
Change CS_DESTROY
2012-03-23 10:38:43.957579 [DEBUG] switch_core_state_machine.c:504
(sofia/internal/gxw4104-fxo1/339XXXXXXX@192.168.0.3:5060) State DESTROY
2012-03-23 10:38:43.957579 [DEBUG] mod_sofia.c:374
sofia/internal/gxw4104-fxo1/339XXXXXXX@192.168.0.3:5060 SOFIA DESTROY
2012-03-23 10:38:43.957579 [DEBUG] switch_core_state_machine.c:86
sofia/internal/gxw4104-fxo1/339XXXXXXX@192.168.0.3:5060 Standard DESTROY
2012-03-23 10:38:43.957579 [DEBUG] switch_core_state_machine.c:504
(sofia/internal/gxw4104-fxo1/339XXXXXXX@192.168.0.3:5060) State DESTROY
going to sleep
freeswitch@internal>
Thank's in advance
Regards
Accursio Avona
_________________________________________________________________________
Professional FreeSWITCH Consulting Services:
consulting@freeswitch.org
http://www.freeswitchsolutions.com
FreeSWITCH-powered IP PBX: The CudaTel Communication Server
http://www.cudatel.com
Official FreeSWITCH Sites
http://www.freeswitch.org
http://wiki.freeswitch.org
http://www.cluecon.com
FreeSWITCH-users mailing list
FreeSWITCH-users@lists.freeswitch.org
http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
http://www.freeswitch.org
--
Brian D. Foster
Endigo Computer LLC
Email: bdfoster@endigotech.com
Phone: 317-800-7876
Indianapolis, Indiana, USA
This message contains confidential information and is intended for those listed in the "To:", "CC:", and/or "BCC:" fields of the message header. If you are not the intended recipient you are notified that disclosing, copying, distributing or taking any action in reliance on the contents of this information is strictly prohibited. E-mail transmission cannot be guaranteed to be secure or error-free as information could be intercepted, corrupted, lost, destroyed, arrive late or incomplete, or contain viruses. The sender therefore does not accept liability for any errors or omissions in the contents of this message, which arise as a result of e-mail transmission. If verification is required please request a hard-copy version.
_________________________________________________________________________ Professional FreeSWITCH Consulting Services: consulting@freeswitch.org http://www.freeswitchsolutions.com FreeSWITCH-powered IP PBX: The CudaTel Communication Server http://www.cudatel.com Official FreeSWITCH Sites http://www.freeswitch.org http://wiki.freeswitch.org http://www.cluecon.com FreeSWITCH-users mailing list FreeSWITCH-users@lists.freeswitch.org http://lists.freeswitch.org/mailman/listinfo/freeswitch-users UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users http://www.freeswitch.org