[Freeswitch-users] Grandstream 4104
a.avona
a.avona at elios.net
Mon Mar 26 14:01:10 MSD 2012
Hi, thank's for your answer
i did as you said and if i digit
originate sofia/internal/XXXX at 192.168.0.3 <mailto:XXXX at 192.168.0.3> 9178
outgoing calls work well.
if i try to originate call from a client it doesn't work so i think the
problem is in the default account configuration
here is the siptraces
Thank's for any suggestion
reagards
Accursio Avona
recv 865 bytes from udp/[192.168.0.200]:5060 at 09:31:18.696255:
------------------------------------------------------------------------
INVITE sip:339XXXXXXXX at 192.168.0.2 SIP/2.0
Via: SIP/2.0/UDP
192.168.0.200:5060;branch=z9hG4bK00be621f9475e11195f9001e68586d6b;rport
From: "1000" <sip:1000 at 192.168.0.2>;tag=645977894
To: <sip:339XXXXXXXX at 192.168.0.2>
Call-ID: 00BE621F-9475-E111-95F8-001E68586D6B at 192.168.0.200
CSeq: 5 INVITE
Contact: <sip:1000 at 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 at 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 at 192.168.0.2>;tag=645977894
To: <sip:339XXXXXXXX at 192.168.0.2>
Call-ID: 00BE621F-9475-E111-95F8-001E68586D6B at 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 at 192.168.0.2>;tag=645977894
To: <sip:339XXXXXXXX at 192.168.0.2>;tag=pvaSD4jQ22N9D
Call-ID: 00BE621F-9475-E111-95F8-001E68586D6B at 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 at 192.168.0.2 SIP/2.0
Via: SIP/2.0/UDP
192.168.0.200:5060;branch=z9hG4bK00be621f9475e11195f9001e68586d6b;rport
From: "1000" <sip:1000 at 192.168.0.2>;tag=645977894
To: <sip:339XXXXXXXX at 192.168.0.2>;tag=pvaSD4jQ22N9D
Call-ID: 00BE621F-9475-E111-95F8-001E68586D6B at 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 at 192.168.0.2 SIP/2.0
Via: SIP/2.0/UDP
192.168.0.200:5060;branch=z9hG4bK00be621f9475e11195fa001e68586d6b;rport
From: "1000" <sip:1000 at 192.168.0.2>;tag=645977894
To: <sip:339XXXXXXXX at 192.168.0.2>
Call-ID: 00BE621F-9475-E111-95F8-001E68586D6B at 192.168.0.200
CSeq: 6 INVITE
Contact: <sip:1000 at 192.168.0.200:5060>
Proxy-Authorization: Digest username="1000", realm="192.168.0.2",
nonce="710d05a6-7726-11e1-bf71-e50b8b76e101",
uri="sip:339XXXXXXXX at 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 at 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 at 192.168.0.2>;tag=645977894
To: <sip:339XXXXXXXX at 192.168.0.2>
Call-ID: 00BE621F-9475-E111-95F8-001E68586D6B at 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 at 192.168.0.2 [710da826-7726-11e1-bf72-e50b8b76e101]
2012-03-26 11:31:18.692558 [DEBUG] sofia.c:5526 Channel
sofia/internal/1000 at 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 at 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 at 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 at 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 at 192.168.0.2 [BREAK]
2012-03-26 11:31:18.692558 [DEBUG] switch_core_state_machine.c:362
(sofia/internal/1000 at 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 at 192.168.0.2) State INIT
2012-03-26 11:31:18.692558 [DEBUG] mod_sofia.c:85
sofia/internal/1000 at 192.168.0.2 SOFIA INIT
2012-03-26 11:31:18.692558 [DEBUG] mod_sofia.c:125
(sofia/internal/1000 at 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 at 192.168.0.2 [BREAK]
2012-03-26 11:31:18.692558 [DEBUG] switch_core_state_machine.c:401
(sofia/internal/1000 at 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 at 192.168.0.2) Running State Change CS_ROUTING
2012-03-26 11:31:18.692558 [DEBUG] switch_channel.c:1886
(sofia/internal/1000 at 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 at 192.168.0.2) State ROUTING
2012-03-26 11:31:18.692558 [DEBUG] mod_sofia.c:148
sofia/internal/1000 at 192.168.0.2 SOFIA ROUTING
2012-03-26 11:31:18.692558 [DEBUG] switch_core_state_machine.c:104
sofia/internal/1000 at 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 at 192.168.0.2 parsing [default->unloop]
continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (PASS) [unloop]
${unroll_loops}(true) =~ /^true$/ break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL) [unloop]
${sip_looped_call}() =~ /^true$/ break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing [default->tod_example]
continue=true
Dialplan: sofia/internal/1000 at 192.168.0.2 Date/Time Match (PASS)
[tod_example] break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 Action set(open=true)
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing
[default->holiday_example] continue=true
Dialplan: sofia/internal/1000 at 192.168.0.2 Date/TimeMatch (FAIL)
[holiday_example] break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing
[default->global-intercept] continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL)
[global-intercept] destination_number(339XXXXXXXX) =~ /^886$/ break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing
[default->group-intercept] continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL) [group-intercept]
destination_number(339XXXXXXXX) =~ /^\*8$/ break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing
[default->intercept-ext] continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL) [intercept-ext]
destination_number(339XXXXXXXX) =~ /^\*\*(\d+)$/ break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing [default->redial]
continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL) [redial]
destination_number(339XXXXXXXX) =~ /^(redial|870)$/ break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing [default->global]
continue=true
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL) [global]
${call_debug}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/1000 at 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 at 192.168.0.2 Absolute Condition [global]
Dialplan: sofia/internal/1000 at 192.168.0.2 Action
hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid})
Dialplan: sofia/internal/1000 at 192.168.0.2 Action
hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
Dialplan: sofia/internal/1000 at 192.168.0.2 Action
hash(insert/${domain_name}-last_dial/global/${uuid})
Dialplan: sofia/internal/1000 at 192.168.0.2 Action
export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing [default->snom-demo-2]
continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL) [snom-demo-2]
destination_number(339XXXXXXXX) =~ /^9001$/ break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing [default->snom-demo-1]
continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL) [snom-demo-1]
destination_number(339XXXXXXXX) =~ /^9000$/ break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing [default->eavesdrop]
continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL) [eavesdrop]
destination_number(339XXXXXXXX) =~ /^88(\d{4})$|^\*0(.*)$/ break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing [default->eavesdrop]
continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL) [eavesdrop]
destination_number(339XXXXXXXX) =~ /^779$/ break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing [default->call_return]
continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL) [call_return]
destination_number(339XXXXXXXX) =~ /^\*69$|^869$|^lcr$/ break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing [default->del-group]
continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL) [del-group]
destination_number(339XXXXXXXX) =~ /^80(\d{2})$/ break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing [default->add-group]
continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL) [add-group]
destination_number(339XXXXXXXX) =~ /^81(\d{2})$/ break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing
[default->call-group-simo] continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL) [call-group-simo]
destination_number(339XXXXXXXX) =~ /^82(\d{2})$/ break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing
[default->call-group-order] continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL)
[call-group-order] destination_number(339XXXXXXXX) =~ /^83(\d{2})$/
break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing
[default->extension-intercom] continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL)
[extension-intercom] destination_number(339XXXXXXXX) =~
/^8(10[01][0-9])$/ break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing
[default->Local_Extension] continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL) [Local_Extension]
destination_number(339XXXXXXXX) =~ /^(10[01][0-9])$/ break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing
[default->Local_Extension_Skinny] continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL)
[Local_Extension_Skinny] destination_number(339XXXXXXXX) =~
/^(11[01][0-9])$/ break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing
[default->group_dial_sales] continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL)
[group_dial_sales] destination_number(339XXXXXXXX) =~ /^2000$/
break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing
[default->group_dial_support] continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL)
[group_dial_support] destination_number(339XXXXXXXX) =~ /^2001$/
break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing
[default->group_dial_billing] continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL)
[group_dial_billing] destination_number(339XXXXXXXX) =~ /^2002$/
break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing [default->operator]
continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL) [operator]
destination_number(339XXXXXXXX) =~ /^(operator|0)$/ break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing [default->vmain]
continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL) [vmain]
destination_number(339XXXXXXXX) =~ /^vmain$|^4000$|^\*98$/ break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing [default->sip_uri]
continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL) [sip_uri]
destination_number(339XXXXXXXX) =~ /^sip:(.*)$/ break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing
[default->nb_conferences] continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL) [nb_conferences]
destination_number(339XXXXXXXX) =~ /^(30\d{2})$/ break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing
[default->wb_conferences] continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL) [wb_conferences]
destination_number(339XXXXXXXX) =~ /^(31\d{2})$/ break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing
[default->uwb_conferences] continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL) [uwb_conferences]
destination_number(339XXXXXXXX) =~ /^(32\d{2})$/ break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing
[default->cdquality_conferences] continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL)
[cdquality_conferences] destination_number(339XXXXXXXX) =~ /^(33\d{2})$/
break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing
[default->freeswitch_public_conf_via_sip] continue=false
Dialplan: sofia/internal/1000 at 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 at 192.168.0.2 parsing
[default->mad_boss_intercom] continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL)
[mad_boss_intercom] destination_number(339XXXXXXXX) =~ /^0911$/
break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing
[default->mad_boss_intercom] continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL)
[mad_boss_intercom] destination_number(339XXXXXXXX) =~ /^0912$/
break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing [default->mad_boss]
continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL) [mad_boss]
destination_number(339XXXXXXXX) =~ /^0913$/ break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing [default->ivr_demo]
continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL) [ivr_demo]
destination_number(339XXXXXXXX) =~ /^5000$/ break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing
[default->dynamic_conference] continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL)
[dynamic_conference] destination_number(339XXXXXXXX) =~ /^5001$/
break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing
[default->rtp_multicast_page] continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL)
[rtp_multicast_page] destination_number(339XXXXXXXX) =~
/^pagegroup$|^7243$/ break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing [default->park]
continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL) [park]
destination_number(339XXXXXXXX) =~ /^5900$/ break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing [default->unpark]
continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL) [unpark]
destination_number(339XXXXXXXX) =~ /^5901$/ break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing [default->valet_park]
continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL) [valet_park]
destination_number(339XXXXXXXX) =~ /^(6000)$/ break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing [default->valet_park]
continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL) [valet_park]
destination_number(339XXXXXXXX) =~ /^(60\d[1-9])$/ break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing [default->park]
continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (PASS) [park]
source(mod_sofia) =~ /mod_sofia/ break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL) [park]
destination_number(339XXXXXXXX) =~ /park\+(\d+)/ break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing [default->unpark]
continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (PASS) [unpark]
source(mod_sofia) =~ /mod_sofia/ break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL) [unpark]
destination_number(339XXXXXXXX) =~ /^parking$/ break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing [default->park]
continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (PASS) [park]
source(mod_sofia) =~ /mod_sofia/ break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL) [park]
destination_number(339XXXXXXXX) =~ /callpark/ break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing [default->unpark]
continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (PASS) [unpark]
source(mod_sofia) =~ /mod_sofia/ break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL) [unpark]
destination_number(339XXXXXXXX) =~ /pickup/ break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing [default->wait]
continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL) [wait]
destination_number(339XXXXXXXX) =~ /^wait$/ break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing [default->fax_receive]
continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL) [fax_receive]
destination_number(339XXXXXXXX) =~ /^9178$/ break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing
[default->fax_transmit] continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL) [fax_transmit]
destination_number(339XXXXXXXX) =~ /^9179$/ break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing
[default->ringback_180] continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL) [ringback_180]
destination_number(339XXXXXXXX) =~ /^9180$/ break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing
[default->ringback_183_uk_ring] continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL)
[ringback_183_uk_ring] destination_number(339XXXXXXXX) =~ /^9181$/
break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing
[default->ringback_183_music_ring] continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL)
[ringback_183_music_ring] destination_number(339XXXXXXXX) =~ /^9182$/
break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing
[default->ringback_post_answer_uk_ring] continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL)
[ringback_post_answer_uk_ring] destination_number(339XXXXXXXX) =~
/^9183$/ break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing
[default->ringback_post_answer_music] continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL)
[ringback_post_answer_music] destination_number(339XXXXXXXX) =~ /^9184$/
break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing [default->ClueCon]
continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL) [ClueCon]
destination_number(339XXXXXXXX) =~ /^9191$/ break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing [default->show_info]
continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL) [show_info]
destination_number(339XXXXXXXX) =~ /^9192$/ break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing
[default->video_record] continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL) [video_record]
destination_number(339XXXXXXXX) =~ /^9193$/ break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing
[default->video_playback] continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL) [video_playback]
destination_number(339XXXXXXXX) =~ /^9194$/ break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing [default->delay_echo]
continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL) [delay_echo]
destination_number(339XXXXXXXX) =~ /^9195$/ break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing [default->echo]
continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL) [echo]
destination_number(339XXXXXXXX) =~ /^9196$/ break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing [default->milliwatt]
continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL) [milliwatt]
destination_number(339XXXXXXXX) =~ /^9197$/ break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing [default->tone_stream]
continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL) [tone_stream]
destination_number(339XXXXXXXX) =~ /^9198$/ break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing
[default->zrtp_enrollement] continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL)
[zrtp_enrollement] destination_number(339XXXXXXXX) =~ /^9787$/
break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing [default->hold_music]
continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL) [hold_music]
destination_number(339XXXXXXXX) =~ /^9664$/ break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing [default->from_pstn]
continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL) [from_pstn]
destination_number(339XXXXXXXX) =~ /^0000$/ break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing [default->101]
continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL) [101]
destination_number(339XXXXXXXX) =~ /^101$/ break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing [default->pizza_demo]
continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (FAIL) [pizza_demo]
destination_number(339XXXXXXXX) =~ /^(pizza|74992)$/ break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 parsing
[default->gxw4104-fxo-local] continue=false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (PASS)
[gxw4104-fxo-local] ${toll_allow}(domestic,international,local) =~
/local/ break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 Regex (PASS)
[gxw4104-fxo-local] destination_number(339XXXXXXXX) =~ /^(\d{6,})$/
break=on-false
Dialplan: sofia/internal/1000 at 192.168.0.2 Action
set(effective_caller_id_number=0321234567)
Dialplan: sofia/internal/1000 at 192.168.0.2 Action
set(effective_caller_id_name=ThisIsMyCompany)
Dialplan: sofia/internal/1000 at 192.168.0.2 Action
set(ignore_early_media=ring_ready)
Dialplan: sofia/internal/1000 at 192.168.0.2 Action set(ringback=${us-ring})
Dialplan: sofia/internal/1000 at 192.168.0.2 Action
bridge(sofia/internal/gxw4104-fxo1/339XXXXXXXX at 192.168.0.3:5060)
2012-03-26 11:31:18.692558 [DEBUG] switch_core_state_machine.c:154
(sofia/internal/1000 at 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 at 192.168.0.2 [BREAK]
2012-03-26 11:31:18.692558 [DEBUG] switch_core_state_machine.c:410
(sofia/internal/1000 at 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 at 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 at 192.168.0.2) State EXECUTE
2012-03-26 11:31:18.692558 [DEBUG] mod_sofia.c:241
sofia/internal/1000 at 192.168.0.2 SOFIA EXECUTE
2012-03-26 11:31:18.692558 [DEBUG] switch_core_state_machine.c:192
sofia/internal/1000 at 192.168.0.2 Standard EXECUTE
EXECUTE sofia/internal/1000 at 192.168.0.2 set(open=true)
2012-03-26 11:31:18.692558 [DEBUG] mod_dptools.c:1281
sofia/internal/1000 at 192.168.0.2 SET [open]=[true]
EXECUTE sofia/internal/1000 at 192.168.0.2
hash(insert/192.168.0.2-spymap/1000/710da826-7726-11e1-bf72-e50b8b76e101)
EXECUTE sofia/internal/1000 at 192.168.0.2
hash(insert/192.168.0.2-last_dial/1000/339XXXXXXXX)
EXECUTE sofia/internal/1000 at 192.168.0.2
hash(insert/192.168.0.2-last_dial/global/710da826-7726-11e1-bf72-e50b8b76e101)
EXECUTE sofia/internal/1000 at 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 at 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 at 192.168.0.2 SET
[effective_caller_id_number]=[0321234567]
EXECUTE sofia/internal/1000 at 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 at 192.168.0.2 SET
[effective_caller_id_name]=[ThisIsMyCompany]
EXECUTE sofia/internal/1000 at 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 at 192.168.0.2 SET [ignore_early_media]=[ring_ready]
EXECUTE sofia/internal/1000 at 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 at 192.168.0.2 SET [ringback]=[%(2000,4000,440,480)]
EXECUTE sofia/internal/1000 at 192.168.0.2
bridge(sofia/internal/gxw4104-fxo1/339XXXXXXXX at 192.168.0.3:5060)
2012-03-26 11:31:18.692558 [DEBUG] switch_channel.c:1047
sofia/internal/1000 at 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 at 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 at 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 at 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 at 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 at 192.168.0.3:5060) State INIT
2012-03-26 11:31:18.712698 [DEBUG] mod_sofia.c:85
sofia/internal/gxw4104-fxo1/339XXXXXXXX at 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 at 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 at 192.168.0.2>;tag=reXaHtmyvm2eN
To: <sip:gxw4104-fxo1/339XXXXXXXX at 192.168.0.3:5060>
Call-ID: 486dc943-f1c9-122f-5cb5-0800276bec7a
CSeq: 26041075 INVITE
Contact: <sip:mod_sofia at 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 at 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 at 192.168.0.3:5060 [BREAK]
2012-03-26 11:31:18.712698 [DEBUG] mod_sofia.c:125
(sofia/internal/gxw4104-fxo1/339XXXXXXXX at 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 at 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 at 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 at 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 at 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 at 192.168.0.3:5060) State ROUTING
2012-03-26 11:31:18.712698 [DEBUG] mod_sofia.c:148
sofia/internal/gxw4104-fxo1/339XXXXXXXX at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 192.168.0.2>;tag=reXaHtmyvm2eN
To: <sip:gxw4104-fxo1/339XXXXXXXX at 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 at 192.168.0.2>;tag=reXaHtmyvm2eN
To: <sip:gxw4104-fxo1/339XXXXXXXX at 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 at 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 at 192.168.0.2>;tag=reXaHtmyvm2eN
To: <sip:gxw4104-fxo1/339XXXXXXXX at 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 at 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 at 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 at 192.168.0.3:5060 [BREAK]
2012-03-26 11:31:18.712698 [DEBUG] sofia.c:5526 Channel
sofia/internal/gxw4104-fxo1/339XXXXXXXX at 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 at 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 at 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 at 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 at 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 at 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 at 192.168.0.2) Callstate Change RINGING -> HANGUP
2012-03-26 11:31:18.712698 [NOTICE] mod_dptools.c:3041 Hangup
sofia/internal/1000 at 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 at 192.168.0.2 [KILL]
2012-03-26 11:31:18.712698 [DEBUG] switch_core_session.c:1180 Send
signal sofia/internal/1000 at 192.168.0.2 [BREAK]
2012-03-26 11:31:18.712698 [DEBUG] switch_core_session.c:2285
sofia/internal/1000 at 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 at 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 at 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 at 192.168.0.2) State HANGUP
2012-03-26 11:31:18.712698 [DEBUG] mod_sofia.c:463
sofia/internal/1000 at 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 at 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 at 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 at 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 at 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 at 192.168.0.2 [BREAK]
2012-03-26 11:31:18.712698 [DEBUG] switch_core_state_machine.c:362
(sofia/internal/1000 at 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 at 192.168.0.2) State REPORTING
2012-03-26 11:31:18.712698 [DEBUG] switch_core_state_machine.c:79
sofia/internal/1000 at 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 at 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 at 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 at 192.168.0.2 [BREAK]
2012-03-26 11:31:18.712698 [DEBUG] switch_core_session.c:1380 Session 13
(sofia/internal/1000 at 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 at 192.168.0.2) Ended
2012-03-26 11:31:18.712698 [NOTICE] switch_core_session.c:1400 Close
Channel sofia/internal/1000 at 192.168.0.2 [CS_DESTROY]
2012-03-26 11:31:18.712698 [DEBUG] switch_core_state_machine.c:491
(sofia/internal/1000 at 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 at 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 at 192.168.0.2) State DESTROY
2012-03-26 11:31:18.712698 [DEBUG] mod_sofia.c:374
sofia/internal/1000 at 192.168.0.2 SOFIA DESTROY
2012-03-26 11:31:18.712698 [DEBUG] switch_core_state_machine.c:86
sofia/internal/1000 at 192.168.0.2 Standard DESTROY
2012-03-26 11:31:18.712698 [DEBUG] switch_core_state_machine.c:504
(sofia/internal/1000 at 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 at 192.168.0.2>;tag=645977894
To: <sip:339XXXXXXXX at 192.168.0.2>;tag=Q53HFZ3tZBcvS
Call-ID: 00BE621F-9475-E111-95F8-001E68586D6B at 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 at 192.168.0.2>
------------------------------------------------------------------------
2012-03-26 11:31:18.712698 [DEBUG] switch_core_state_machine.c:602
(sofia/internal/gxw4104-fxo1/339XXXXXXXX at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 192.168.0.2 SIP/2.0
Via: SIP/2.0/UDP
192.168.0.200:5060;branch=z9hG4bK00be621f9475e11195fa001e68586d6b;rport
From: "1000" <sip:1000 at 192.168.0.2>;tag=645977894
To: <sip:339XXXXXXXX at 192.168.0.2>;tag=Q53HFZ3tZBcvS
Call-ID: 00BE621F-9475-E111-95F8-001E68586D6B at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 192.168.0.3:5060) State DESTROY
2012-03-26 11:31:18.712698 [DEBUG] mod_sofia.c:374
sofia/internal/gxw4104-fxo1/339XXXXXXXX at 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 at 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 at 192.168.0.3:5060) State DESTROY
going to sleep
freeswitch at internal>
Il 25/03/2012 06:39, Anton Kvashenkin ha scritto:
> originate sofia/internal/XXXX at 192.168.0.3 <mailto:XXXX at 192.168.0.3> 9178
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20120326/d101e370/attachment-0001.html
Join us at ClueCon 2011 Aug 9-11, 2011
More information about the FreeSWITCH-users
mailing list