[Freeswitch-users] Freeswitch installed, ATA registered but no call are possible...

Sat Dec 3 19:31:51 MSK 2011

Hi,

The trace I gave was not a good one. The one I show now Is a good sample.
Any attempt to dial "1001" results only in a response by voicemail. Any
answer ?

Thanks

/The trace :/

Dialplan: sofia/internal/1004 at connexur.dyndns.org Absolute Condition
[global]
Dialplan: sofia/internal/1004 at connexur.dyndns.org Action
hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid})
Dialplan: sofia/internal/1004 at connexur.dyndns.org Action
hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
Dialplan: sofia/internal/1004 at connexur.dyndns.org Action
hash(insert/${domain_name}-last_dial/global/${uuid})
Dialplan: sofia/internal/1004 at connexur.dyndns.org parsing
[default->snom-demo-2] continue=false
Dialplan: sofia/internal/1004 at connexur.dyndns.org Regex (FAIL) [snom-demo-2]
destination_number(1001) =~ /^9001$/ break=on-false
Dialplan: sofia/internal/1004 at connexur.dyndns.org parsing
[default->snom-demo-1] continue=false
Dialplan: sofia/internal/1004 at connexur.dyndns.org Regex (FAIL) [snom-demo-1]
destination_number(1001) =~ /^9000$/ break=on-false
Dialplan: sofia/internal/1004 at connexur.dyndns.org parsing
[default->eavesdrop] continue=false
Dialplan: sofia/internal/1004 at connexur.dyndns.org Regex (FAIL) [eavesdrop]
destination_number(1001) =~ /^88(.*)$|^\*0(.*)$/ break=on-false
Dialplan: sofia/internal/1004 at connexur.dyndns.org parsing
[default->eavesdrop] continue=false
Dialplan: sofia/internal/1004 at connexur.dyndns.org Regex (FAIL) [eavesdrop]
destination_number(1001) =~ /^779$/ break=on-false
Dialplan: sofia/internal/1004 at connexur.dyndns.org parsing
[default->call_return] continue=false
Dialplan: sofia/internal/1004 at connexur.dyndns.org Regex (FAIL) [call_return]
destination_number(1001) =~ /^\*69$|^869$|^lcr$/ break=on-false
Dialplan: sofia/internal/1004 at connexur.dyndns.org parsing
[default->del-group] continue=false
Dialplan: sofia/internal/1004 at connexur.dyndns.org Regex (FAIL) [del-group]
destination_number(1001) =~ /^80(\d{2})$/ break=on-false
Dialplan: sofia/internal/1004 at connexur.dyndns.org parsing
[default->add-group] continue=false
Dialplan: sofia/internal/1004 at connexur.dyndns.org Regex (FAIL) [add-group]
destination_number(1001) =~ /^81(\d{2})$/ break=on-false
Dialplan: sofia/internal/1004 at connexur.dyndns.org parsing
[default->call-group-simo] continue=false
Dialplan: sofia/internal/1004 at connexur.dyndns.org Regex (FAIL)
[call-group-simo] destination_number(1001) =~ /^82(\d{2})$/ break=on-false
Dialplan: sofia/internal/1004 at connexur.dyndns.org parsing
[default->call-group-order] continue=false
Dialplan: sofia/internal/1004 at connexur.dyndns.org Regex (FAIL)
[call-group-order] destination_number(1001) =~ /^83(\d{2})$/ break=on-false
Dialplan: sofia/internal/1004 at connexur.dyndns.org parsing
[default->extension-intercom] continue=false
Dialplan: sofia/internal/1004 at connexur.dyndns.org Regex (FAIL)
[extension-intercom] destination_number(1001) =~ /^8(10[01][0-9])$/
break=on-false
Dialplan: sofia/internal/1004 at connexur.dyndns.org parsing
[default->Local_Extension] continue=false
Dialplan: sofia/internal/1004 at connexur.dyndns.org Regex (PASS)
[Local_Extension] destination_number(1001) =~ /^(10[01][0-9])$/
break=on-false
Dialplan: sofia/internal/1004 at connexur.dyndns.org Action
set(dialed_extension=1001)
Dialplan: sofia/internal/1004 at connexur.dyndns.org Action
export(dialed_extension=1001)
Dialplan: sofia/internal/1004 at connexur.dyndns.org Action bind_meta_app(1 b s
execute_extension::dx XML features)
Dialplan: sofia/internal/1004 at connexur.dyndns.org Action bind_meta_app(2 b s
record_session::/usr/local/freeswitch/recordings/${caller_id_number}.${strftime(%Y-%m-%d-%H-%M-%S)}.wav)
Dialplan: sofia/internal/1004 at connexur.dyndns.org Action bind_meta_app(3 b s
execute_extension::cf XML features)
Dialplan: sofia/internal/1004 at connexur.dyndns.org Action
set(ringback=${us-ring})
Dialplan: sofia/internal/1004 at connexur.dyndns.org Action
set(transfer_ringback=local_stream://moh)
Dialplan: sofia/internal/1004 at connexur.dyndns.org Action
set(call_timeout=30)
Dialplan: sofia/internal/1004 at connexur.dyndns.org Action
set(hangup_after_bridge=true)
Dialplan: sofia/internal/1004 at connexur.dyndns.org Action
set(continue_on_fail=true)
Dialplan: sofia/internal/1004 at connexur.dyndns.org Action
hash(insert/${domain_name}-call_return/${dialed_extension}/${caller_id_number})
Dialplan: sofia/internal/1004 at connexur.dyndns.org Action
hash(insert/${domain_name}-last_dial_ext/${dialed_extension}/${uuid})
Dialplan: sofia/internal/1004 at connexur.dyndns.org Action
set(called_party_callgroup=${user_data(${dialed_extension}@${domain_name}
var callgroup)})
Dialplan: sofia/internal/1004 at connexur.dyndns.org Action
hash(insert/${domain_name}-last_dial/${called_party_callgroup}/${uuid})
Dialplan: sofia/internal/1004 at connexur.dyndns.org Action
bridge(user/${dialed_extension}@${domain_name})
Dialplan: sofia/internal/1004 at connexur.dyndns.org Action answer()
Dialplan: sofia/internal/1004 at connexur.dyndns.org Action sleep(1000)
Dialplan: sofia/internal/1004 at connexur.dyndns.org Action voicemail(default
${domain_name} ${dialed_extension})
2011-12-03 17:28:50.405366 [DEBUG] switch_core_state_machine.c:154
(sofia/internal/1004 at connexur.dyndns.org) State Change CS_ROUTING ->
CS_EXECUTE
2011-12-03 17:28:50.405366 [DEBUG] switch_core_session.c:1177 Send signal
sofia/internal/1004 at connexur.dyndns.org [BREAK]
2011-12-03 17:28:50.405366 [DEBUG] switch_core_state_machine.c:410
(sofia/internal/1004 at connexur.dyndns.org) State ROUTING going to sleep
2011-12-03 17:28:50.405366 [DEBUG] switch_core_state_machine.c:362
(sofia/internal/1004 at connexur.dyndns.org) Running State Change CS_EXECUTE
2011-12-03 17:28:50.405366 [DEBUG] switch_core_state_machine.c:417
(sofia/internal/1004 at connexur.dyndns.org) State EXECUTE
2011-12-03 17:28:50.405366 [DEBUG] mod_sofia.c:241
sofia/internal/1004 at connexur.dyndns.org SOFIA EXECUTE
2011-12-03 17:28:50.405366 [DEBUG] switch_core_state_machine.c:192
sofia/internal/1004 at connexur.dyndns.org Standard EXECUTE
EXECUTE sofia/internal/1004 at connexur.dyndns.org
hash(insert/91.204.116.116-spymap/1004/e1f1595c-1dcb-11e1-b3b3-b530f603990b)
EXECUTE sofia/internal/1004 at connexur.dyndns.org
hash(insert/91.204.116.116-last_dial/1004/1001)
EXECUTE sofia/internal/1004 at connexur.dyndns.org
hash(insert/91.204.116.116-last_dial/global/e1f1595c-1dcb-11e1-b3b3-b530f603990b)
EXECUTE sofia/internal/1004 at connexur.dyndns.org set(dialed_extension=1001)
2011-12-03 17:28:50.405366 [DEBUG] mod_dptools.c:1263
sofia/internal/1004 at connexur.dyndns.org SET [dialed_extension]=[1001]
EXECUTE sofia/internal/1004 at connexur.dyndns.org
export(dialed_extension=1001)
2011-12-03 17:28:50.405366 [DEBUG] switch_channel.c:1087 EXPORT
(export_vars) [dialed_extension]=[1001]
EXECUTE sofia/internal/1004 at connexur.dyndns.org bind_meta_app(1 b s
execute_extension::dx XML features)
2011-12-03 17:28:50.405366 [INFO] switch_ivr_async.c:3164 Bound B-Leg: *1
execute_extension::dx XML features
EXECUTE sofia/internal/1004 at connexur.dyndns.org bind_meta_app(2 b s
record_session::/usr/local/freeswitch/recordings/1004.2011-12-03-17-28-50.wav)
2011-12-03 17:28:50.405366 [INFO] switch_ivr_async.c:3164 Bound B-Leg: *2
record_session::/usr/local/freeswitch/recordings/1004.2011-12-03-17-28-50.wav
EXECUTE sofia/internal/1004 at connexur.dyndns.org bind_meta_app(3 b s
execute_extension::cf XML features)
2011-12-03 17:28:50.405366 [INFO] switch_ivr_async.c:3164 Bound B-Leg: *3
execute_extension::cf XML features
EXECUTE sofia/internal/1004 at connexur.dyndns.org
set(ringback=%(2000,4000,440.0,480.0))
2011-12-03 17:28:50.405366 [DEBUG] mod_dptools.c:1263
sofia/internal/1004 at connexur.dyndns.org SET
[ringback]=[%(2000,4000,440.0,480.0)]
EXECUTE sofia/internal/1004 at connexur.dyndns.org
set(transfer_ringback=local_stream://moh)
2011-12-03 17:28:50.405366 [DEBUG] mod_dptools.c:1263
sofia/internal/1004 at connexur.dyndns.org SET
[transfer_ringback]=[local_stream://moh]
EXECUTE sofia/internal/1004 at connexur.dyndns.org set(call_timeout=30)
2011-12-03 17:28:50.405366 [DEBUG] mod_dptools.c:1263
sofia/internal/1004 at connexur.dyndns.org SET [call_timeout]=[30]
EXECUTE sofia/internal/1004 at connexur.dyndns.org
set(hangup_after_bridge=true)
2011-12-03 17:28:50.405366 [DEBUG] mod_dptools.c:1263
sofia/internal/1004 at connexur.dyndns.org SET [hangup_after_bridge]=[true]
EXECUTE sofia/internal/1004 at connexur.dyndns.org set(continue_on_fail=true)
2011-12-03 17:28:50.405366 [DEBUG] mod_dptools.c:1263
sofia/internal/1004 at connexur.dyndns.org SET [continue_on_fail]=[true]
EXECUTE sofia/internal/1004 at connexur.dyndns.org
hash(insert/91.204.116.116-call_return/1001/1004)
EXECUTE sofia/internal/1004 at connexur.dyndns.org
hash(insert/91.204.116.116-last_dial_ext/1001/e1f1595c-1dcb-11e1-b3b3-b530f603990b)
EXECUTE sofia/internal/1004 at connexur.dyndns.org
set(called_party_callgroup=techsupport)
2011-12-03 17:28:50.405366 [DEBUG] mod_dptools.c:1263
sofia/internal/1004 at connexur.dyndns.org SET
[called_party_callgroup]=[techsupport]
EXECUTE sofia/internal/1004 at connexur.dyndns.org
hash(insert/91.204.116.116-last_dial/techsupport/e1f1595c-1dcb-11e1-b3b3-b530f603990b)
EXECUTE sofia/internal/1004 at connexur.dyndns.org
bridge(user/1001 at 91.204.116.116)
2011-12-03 17:28:50.405366 [DEBUG] switch_channel.c:1041
sofia/internal/1004 at connexur.dyndns.org EXPORTING[export_vars]
[dialed_extension]=[1001] to event
2011-12-03 17:28:50.405366 [DEBUG] switch_ivr_originate.c:1884 Parsing
global variables
2011-12-03 17:28:50.405366 [DEBUG] switch_channel.c:1041
sofia/internal/1004 at connexur.dyndns.org EXPORTING[export_vars]
[dialed_extension]=[1001] to event
2011-12-03 17:28:50.405366 [DEBUG] switch_ivr_originate.c:1884 Parsing
global variables
2011-12-03 17:28:50.405366 [DEBUG] switch_event.c:1521 Parsing variable
[presence_id]=[1001 at 91.204.116.116]
2011-12-03 17:28:50.405366 [NOTICE] switch_channel.c:920 New Channel
sofia/internal/sip:1001 at 192.168.0.43:5080
[e1f3aea0-1dcb-11e1-b3bb-b530f603990b]
2011-12-03 17:28:50.405366 [DEBUG] mod_sofia.c:4557
(sofia/internal/sip:1001 at 192.168.0.43:5080) State Change CS_NEW -> CS_INIT
2011-12-03 17:28:50.405366 [DEBUG] switch_core_session.c:1177 Send signal
sofia/internal/sip:1001 at 192.168.0.43:5080 [BREAK]
2011-12-03 17:28:50.405366 [DEBUG] switch_core_state_machine.c:362
(sofia/internal/sip:1001 at 192.168.0.43:5080) Running State Change CS_INIT
2011-12-03 17:28:50.405366 [DEBUG] switch_core_state_machine.c:401
(sofia/internal/sip:1001 at 192.168.0.43:5080) State INIT
2011-12-03 17:28:50.405366 [DEBUG] mod_sofia.c:85
sofia/internal/sip:1001 at 192.168.0.43:5080 SOFIA INIT
2011-12-03 17:28:50.405366 [DEBUG] sofia_glue.c:2448
sip:1001 at 93.20.214.53:5080 Setting proxy route to
sofia/internal/sip:1001 at 192.168.0.43:5080
2011-12-03 17:28:50.405366 [DEBUG] mod_sofia.c:125
(sofia/internal/sip:1001 at 192.168.0.43:5080) State Change CS_INIT ->
CS_ROUTING
2011-12-03 17:28:50.405366 [DEBUG] switch_core_session.c:1177 Send signal
sofia/internal/sip:1001 at 192.168.0.43:5080 [BREAK]
2011-12-03 17:28:50.405366 [DEBUG] switch_core_state_machine.c:401
(sofia/internal/sip:1001 at 192.168.0.43:5080) State INIT going to sleep
2011-12-03 17:28:50.405366 [DEBUG] switch_core_state_machine.c:362
(sofia/internal/sip:1001 at 192.168.0.43:5080) Running State Change CS_ROUTING
2011-12-03 17:28:50.405366 [DEBUG] switch_channel.c:1871
(sofia/internal/sip:1001 at 192.168.0.43:5080) Callstate Change DOWN -> RINGING
2011-12-03 17:28:50.405366 [DEBUG] switch_core_state_machine.c:410
(sofia/internal/sip:1001 at 192.168.0.43:5080) State ROUTING
2011-12-03 17:28:50.405366 [DEBUG] mod_sofia.c:148
sofia/internal/sip:1001 at 192.168.0.43:5080 SOFIA ROUTING
2011-12-03 17:28:50.405366 [DEBUG] switch_ivr_originate.c:66
(sofia/internal/sip:1001 at 192.168.0.43:5080) State Change CS_ROUTING ->
CS_CONSUME_MEDIA
2011-12-03 17:28:50.405366 [DEBUG] switch_core_session.c:1177 Send signal
sofia/internal/sip:1001 at 192.168.0.43:5080 [BREAK]
2011-12-03 17:28:50.405366 [DEBUG] switch_core_state_machine.c:410
(sofia/internal/sip:1001 at 192.168.0.43:5080) State ROUTING going to sleep
2011-12-03 17:28:50.405366 [DEBUG] switch_core_state_machine.c:362
(sofia/internal/sip:1001 at 192.168.0.43:5080) Running State Change
CS_CONSUME_MEDIA
2011-12-03 17:28:50.405366 [DEBUG] switch_core_state_machine.c:429
(sofia/internal/sip:1001 at 192.168.0.43:5080) State CONSUME_MEDIA
2011-12-03 17:28:50.405366 [DEBUG] switch_core_state_machine.c:429
(sofia/internal/sip:1001 at 192.168.0.43:5080) State CONSUME_MEDIA going to
sleep
2011-12-03 17:28:50.405366 [DEBUG] switch_core_session.c:872 Send signal
sofia/internal/sip:1001 at 192.168.0.43:5080 [BREAK]
2011-12-03 17:28:50.405366 [DEBUG] sofia.c:5368 Channel
sofia/internal/sip:1001 at 192.168.0.43:5080 entering state [calling][0]
2011-12-03 17:28:50.525351 [DEBUG] switch_core_session.c:872 Send signal
sofia/internal/sip:1001 at 192.168.0.43:5080 [BREAK]
2011-12-03 17:28:50.525351 [DEBUG] switch_core_session.c:872 Send signal
sofia/internal/sip:1001 at 192.168.0.43:5080 [BREAK]
2011-12-03 17:28:50.525351 [INFO] sofia.c:830
sofia/internal/sip:1001 at 192.168.0.43:5080 Update Callee ID to "1001"
<sip:1001 at 192.168.0.22>
2011-12-03 17:28:50.525351 [DEBUG] sofia.c:5368 Channel
sofia/internal/sip:1001 at 192.168.0.43:5080 entering state [completing][200]
2011-12-03 17:28:50.525351 [DEBUG] sofia.c:5379 Remote SDP:
v=0
o=FreeSWITCH 1322901237 1322901238 IN IP4 192.168.0.22
s=FreeSWITCH
c=IN IP4 192.168.0.22
t=0 0
m=audio 28016 RTP/AVP 0 101 13
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=rtpmap:13 CN/8000
a=ptime:20

2011-12-03 17:28:50.525351 [DEBUG] switch_core_session.c:872 Send signal
sofia/internal/sip:1001 at 192.168.0.43:5080 [BREAK]
2011-12-03 17:28:50.525351 [DEBUG] switch_core_session.c:872 Send signal
sofia/internal/sip:1001 at 192.168.0.43:5080 [BREAK]
2011-12-03 17:28:50.525351 [DEBUG] sofia.c:5368 Channel
sofia/internal/sip:1001 at 192.168.0.43:5080 entering state [ready][200]
2011-12-03 17:28:50.525351 [DEBUG] sofia_glue.c:4767 Audio Codec Compare
[PCMU:0:8000:20:64000]/[PCMU:0:8000:20:64000]
2011-12-03 17:28:50.525351 [DEBUG] sofia_glue.c:2888 Set Codec
sofia/internal/sip:1001 at 192.168.0.43:5080 PCMU/8000 20 ms 160 samples 64000
bits
2011-12-03 17:28:50.525351 [DEBUG] sofia_glue.c:4881 Set 2833 dtmf send
payload to 101
2011-12-03 17:28:50.525351 [DEBUG] sofia_glue.c:3140 AUDIO RTP
[sofia/internal/sip:1001 at 192.168.0.43:5080] 91.204.116.116 port 28276 ->
192.168.0.22 port 28016 codec: 0 ms: 20
2011-12-03 17:28:50.525351 [DEBUG] switch_rtp.c:1642 Starting timer [soft]
160 bytes per 20ms
2011-12-03 17:28:50.525351 [DEBUG] sofia_glue.c:3404 Set 2833 dtmf send
payload to 101
2011-12-03 17:28:50.525351 [DEBUG] sofia_glue.c:3410 Set 2833 dtmf receive
payload to 101
2011-12-03 17:28:50.525351 [DEBUG] sofia_glue.c:3429 Set comfort noise
payload to 13
2011-12-03 17:28:50.525351 [DEBUG] switch_channel.c:3175
(sofia/internal/sip:1001 at 192.168.0.43:5080) Callstate Change RINGING ->
ACTIVE
2011-12-03 17:28:50.525351 [DEBUG] switch_channel.c:3187 Send signal
sofia/internal/1004 at connexur.dyndns.org [BREAK]
2011-12-03 17:28:50.525351 [NOTICE] sofia.c:6077 Channel
[sofia/internal/sip:1001 at 192.168.0.43:5080] has been answered
2011-12-03 17:28:50.546367 [DEBUG] sofia_glue.c:3140 AUDIO RTP
[sofia/internal/1004 at connexur.dyndns.org] 91.204.116.116 port 21574 ->
192.168.1.10 port 45044 codec: 0 ms: 20
2011-12-03 17:28:50.546367 [DEBUG] switch_rtp.c:1642 Starting timer [soft]
160 bytes per 20ms
2011-12-03 17:28:50.546367 [DEBUG] sofia_glue.c:3404 Set 2833 dtmf send
payload to 101
2011-12-03 17:28:50.546367 [DEBUG] sofia_glue.c:3410 Set 2833 dtmf receive
payload to 101
2011-12-03 17:28:50.546367 [DEBUG] mod_sofia.c:746 Local SDP
sofia/internal/1004 at connexur.dyndns.org:
v=0
o=FreeSWITCH 1322908156 1322908157 IN IP4 91.204.116.116
s=FreeSWITCH
c=IN IP4 91.204.116.116
t=0 0
m=audio 21574 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -
a=ptime:20
a=sendrecv

2011-12-03 17:28:50.546367 [DEBUG] switch_core_session.c:872 Send signal
sofia/internal/1004 at connexur.dyndns.org [BREAK]
2011-12-03 17:28:50.546367 [DEBUG] sofia.c:5368 Channel
sofia/internal/1004 at connexur.dyndns.org entering state [completed][200]
2011-12-03 17:28:50.546367 [DEBUG] switch_core_session.c:726 Send signal
sofia/internal/1004 at connexur.dyndns.org [BREAK]
2011-12-03 17:28:50.546367 [DEBUG] switch_channel.c:3175
(sofia/internal/1004 at connexur.dyndns.org) Callstate Change RINGING -> ACTIVE
2011-12-03 17:28:50.546367 [NOTICE] switch_ivr_originate.c:3209 Channel
[sofia/internal/1004 at connexur.dyndns.org] has been answered
2011-12-03 17:28:50.546367 [DEBUG] switch_ivr_originate.c:3269 Originate
Resulted in Success: [sofia/internal/sip:1001 at 192.168.0.43:5080]
2011-12-03 17:28:50.546367 [DEBUG] switch_ivr_originate.c:3514
(sofia/internal/sip:1001 at 192.168.0.43:5080) State Change CS_CONSUME_MEDIA ->
CS_HIBERNATE
2011-12-03 17:28:50.546367 [DEBUG] switch_core_session.c:1177 Send signal
sofia/internal/sip:1001 at 192.168.0.43:5080 [BREAK]
2011-12-03 17:28:50.546367 [DEBUG] switch_core_state_machine.c:362
(sofia/internal/sip:1001 at 192.168.0.43:5080) Running State Change
CS_HIBERNATE
2011-12-03 17:28:50.546367 [DEBUG] switch_core_state_machine.c:432
(sofia/internal/sip:1001 at 192.168.0.43:5080) State HIBERNATE
2011-12-03 17:28:50.546367 [DEBUG] mod_sofia.c:222
sofia/internal/sip:1001 at 192.168.0.43:5080 SOFIA HIBERNATE
2011-12-03 17:28:50.546367 [DEBUG] switch_core_state_machine.c:261
sofia/internal/sip:1001 at 192.168.0.43:5080 Standard HIBERNATE
2011-12-03 17:28:50.546367 [DEBUG] switch_core_state_machine.c:432
(sofia/internal/sip:1001 at 192.168.0.43:5080) State HIBERNATE going to sleep
2011-12-03 17:28:50.546367 [DEBUG] switch_ivr_originate.c:3269 Originate
Resulted in Success: [sofia/internal/sip:1001 at 192.168.0.43:5080]
2011-12-03 17:28:50.546367 [DEBUG] switch_ivr_bridge.c:1197
(sofia/internal/sip:1001 at 192.168.0.43:5080) State Change CS_HIBERNATE ->
CS_CONSUME_MEDIA
2011-12-03 17:28:50.546367 [DEBUG] switch_core_session.c:1177 Send signal
sofia/internal/sip:1001 at 192.168.0.43:5080 [BREAK]
2011-12-03 17:28:50.546367 [DEBUG] switch_core_state_machine.c:362
(sofia/internal/sip:1001 at 192.168.0.43:5080) Running State Change
CS_CONSUME_MEDIA
2011-12-03 17:28:50.546367 [DEBUG] switch_core_state_machine.c:429
(sofia/internal/sip:1001 at 192.168.0.43:5080) State CONSUME_MEDIA
2011-12-03 17:28:50.546367 [DEBUG] switch_core_state_machine.c:429
(sofia/internal/sip:1001 at 192.168.0.43:5080) State CONSUME_MEDIA going to
sleep
2011-12-03 17:28:50.546367 [DEBUG] switch_core_session.c:726 Send signal
sofia/internal/sip:1001 at 192.168.0.43:5080 [BREAK]
2011-12-03 17:28:50.546367 [DEBUG] switch_core_session.c:726 Send signal
sofia/internal/1004 at connexur.dyndns.org [BREAK]
2011-12-03 17:28:50.546367 [DEBUG] switch_ivr_bridge.c:1289
(sofia/internal/sip:1001 at 192.168.0.43:5080) State Change CS_CONSUME_MEDIA ->
CS_EXCHANGE_MEDIA
2011-12-03 17:28:50.546367 [DEBUG] switch_core_session.c:1177 Send signal
sofia/internal/sip:1001 at 192.168.0.43:5080 [BREAK]
2011-12-03 17:28:50.546367 [DEBUG] switch_core_state_machine.c:362
(sofia/internal/sip:1001 at 192.168.0.43:5080) Running State Change
CS_EXCHANGE_MEDIA
2011-12-03 17:28:50.546367 [DEBUG] switch_core_state_machine.c:420
(sofia/internal/sip:1001 at 192.168.0.43:5080) State EXCHANGE_MEDIA
2011-12-03 17:28:50.546367 [DEBUG] mod_sofia.c:574 SOFIA EXCHANGE_MEDIA
2011-12-03 17:28:50.805377 [DEBUG] switch_core_session.c:872 Send signal
sofia/internal/1004 at connexur.dyndns.org [BREAK]
2011-12-03 17:28:50.805377 [DEBUG] switch_core_session.c:872 Send signal
sofia/internal/1004 at connexur.dyndns.org [BREAK]
2011-12-03 17:28:50.805377 [DEBUG] switch_core_session.c:872 Send signal
sofia/internal/1004 at connexur.dyndns.org [BREAK]
2011-12-03 17:28:50.825358 [DEBUG] sofia.c:5368 Channel
sofia/internal/1004 at connexur.dyndns.org entering state [ready][200]
2011-12-03 17:28:50.825358 [DEBUG] switch_core_session.c:788 Send signal
sofia/internal/sip:1001 at 192.168.0.43:5080 [BREAK]
2011-12-03 17:28:50.825358 [DEBUG] switch_core_session.c:788 Send signal
sofia/internal/1004 at connexur.dyndns.org [BREAK]
2011-12-03 17:28:50.905374 [INFO] switch_rtp.c:3170 Auto Changing port from
192.168.1.10:45044 to 92.145.203.187:45044
2011-12-03 17:28:50.905374 [DEBUG] switch_core_session.c:872 Send signal
sofia/internal/sip:1001 at 192.168.0.43:5080 [BREAK]
2011-12-03 17:28:51.885388 [INFO] switch_rtp.c:3170 Auto Changing port from
192.168.0.22:28016 to 93.20.214.53:28016
2011-12-03 17:29:00.775407 [DEBUG] switch_core_session.c:872 Send signal
sofia/internal/1004 at connexur.dyndns.org [BREAK]
2011-12-03 17:29:00.795373 [DEBUG] switch_channel.c:2833
(sofia/internal/1004 at connexur.dyndns.org) Callstate Change ACTIVE -> HANGUP
2011-12-03 17:29:00.795373 [NOTICE] sofia.c:634 Hangup
sofia/internal/1004 at connexur.dyndns.org [CS_EXECUTE] [NORMAL_CLEARING]
2011-12-03 17:29:00.795373 [DEBUG] switch_channel.c:2856 Send signal
sofia/internal/1004 at connexur.dyndns.org [KILL]
2011-12-03 17:29:00.795373 [DEBUG] switch_core_session.c:1177 Send signal
sofia/internal/1004 at connexur.dyndns.org [BREAK]
2011-12-03 17:29:00.795373 [DEBUG] switch_ivr_bridge.c:586 BRIDGE THREAD
DONE [sofia/internal/1004 at connexur.dyndns.org]
2011-12-03 17:29:00.795373 [DEBUG] switch_ivr_bridge.c:611 Send signal
sofia/internal/sip:1001 at 192.168.0.43:5080 [BREAK]
2011-12-03 17:29:00.815375 [DEBUG] switch_ivr_bridge.c:586 BRIDGE THREAD
DONE [sofia/internal/sip:1001 at 192.168.0.43:5080]
2011-12-03 17:29:00.815375 [DEBUG] switch_ivr_bridge.c:611 Send signal
sofia/internal/1004 at connexur.dyndns.org [BREAK]
2011-12-03 17:29:00.815375 [DEBUG] switch_channel.c:2833
(sofia/internal/sip:1001 at 192.168.0.43:5080) Callstate Change ACTIVE ->
HANGUP
2011-12-03 17:29:00.815375 [NOTICE] switch_ivr_bridge.c:669 Hangup
sofia/internal/sip:1001 at 192.168.0.43:5080 [CS_EXCHANGE_MEDIA]
[NORMAL_CLEARING]
2011-12-03 17:29:00.815375 [DEBUG] switch_channel.c:2856 Send signal
sofia/internal/sip:1001 at 192.168.0.43:5080 [KILL]
2011-12-03 17:29:00.815375 [DEBUG] switch_core_session.c:1177 Send signal
sofia/internal/sip:1001 at 192.168.0.43:5080 [BREAK]
2011-12-03 17:29:00.815375 [DEBUG] switch_core_state_machine.c:420
(sofia/internal/sip:1001 at 192.168.0.43:5080) State EXCHANGE_MEDIA going to
sleep
2011-12-03 17:29:00.815375 [DEBUG] switch_core_state_machine.c:362
(sofia/internal/sip:1001 at 192.168.0.43:5080) Running State Change CS_HANGUP
2011-12-03 17:29:00.815375 [DEBUG] switch_core_state_machine.c:602
(sofia/internal/sip:1001 at 192.168.0.43:5080) State HANGUP
2011-12-03 17:29:00.815375 [DEBUG] mod_sofia.c:465 Channel
sofia/internal/sip:1001 at 192.168.0.43:5080 hanging up, cause: NORMAL_CLEARING
2011-12-03 17:29:00.815375 [DEBUG] mod_sofia.c:509 Sending BYE to
sofia/internal/sip:1001 at 192.168.0.43:5080
2011-12-03 17:29:00.815375 [DEBUG] switch_core_state_machine.c:47
sofia/internal/sip:1001 at 192.168.0.43:5080 Standard HANGUP, cause:
NORMAL_CLEARING
2011-12-03 17:29:00.815375 [DEBUG] switch_core_state_machine.c:602
(sofia/internal/sip:1001 at 192.168.0.43:5080) State HANGUP going to sleep
2011-12-03 17:29:00.815375 [DEBUG] switch_core_state_machine.c:393
(sofia/internal/sip:1001 at 192.168.0.43:5080) State Change CS_HANGUP ->
CS_REPORTING
2011-12-03 17:29:00.815375 [DEBUG] switch_core_session.c:1177 Send signal
sofia/internal/sip:1001 at 192.168.0.43:5080 [BREAK]
2011-12-03 17:29:00.815375 [DEBUG] switch_core_state_machine.c:362
(sofia/internal/sip:1001 at 192.168.0.43:5080) Running State Change
CS_REPORTING
2011-12-03 17:29:00.815375 [DEBUG] switch_core_state_machine.c:662
(sofia/internal/sip:1001 at 192.168.0.43:5080) State REPORTING
2011-12-03 17:29:00.815375 [DEBUG] switch_core_state_machine.c:79
sofia/internal/sip:1001 at 192.168.0.43:5080 Standard REPORTING, cause:
NORMAL_CLEARING
2011-12-03 17:29:00.815375 [DEBUG] switch_core_state_machine.c:662
(sofia/internal/sip:1001 at 192.168.0.43:5080) State REPORTING going to sleep
2011-12-03 17:29:00.815375 [DEBUG] switch_core_state_machine.c:387
(sofia/internal/sip:1001 at 192.168.0.43:5080) State Change CS_REPORTING ->
CS_DESTROY
2011-12-03 17:29:00.815375 [DEBUG] switch_core_session.c:1177 Send signal
sofia/internal/sip:1001 at 192.168.0.43:5080 [BREAK]
2011-12-03 17:29:00.815375 [DEBUG] switch_core_session.c:1377 Session 2
(sofia/internal/sip:1001 at 192.168.0.43:5080) Locked, Waiting on external
entities
2011-12-03 17:29:00.815375 [DEBUG] switch_ivr_bridge.c:1367
sofia/internal/1004 at connexur.dyndns.org skip receive message [UNBRIDGE]
(channel is hungup already)
2011-12-03 17:29:00.815375 [DEBUG] switch_core_session.c:2272
sofia/internal/1004 at connexur.dyndns.org skip receive message
[APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2011-12-03 17:29:00.815375 [DEBUG] switch_core_state_machine.c:417
(sofia/internal/1004 at connexur.dyndns.org) State EXECUTE going to sleep
2011-12-03 17:29:00.815375 [DEBUG] switch_core_state_machine.c:362
(sofia/internal/1004 at connexur.dyndns.org) Running State Change CS_HANGUP
2011-12-03 17:29:00.815375 [DEBUG] switch_core_state_machine.c:602
(sofia/internal/1004 at connexur.dyndns.org) State HANGUP
2011-12-03 17:29:00.815375 [DEBUG] mod_sofia.c:459
sofia/internal/1004 at connexur.dyndns.org Overriding SIP cause 480 with 200
from the other leg
2011-12-03 17:29:00.815375 [DEBUG] mod_sofia.c:465 Channel
sofia/internal/1004 at connexur.dyndns.org hanging up, cause: NORMAL_CLEARING
2011-12-03 17:29:00.815375 [DEBUG] switch_core_state_machine.c:47
sofia/internal/1004 at connexur.dyndns.org Standard HANGUP, cause:
NORMAL_CLEARING
2011-12-03 17:29:00.815375 [DEBUG] switch_core_state_machine.c:602
(sofia/internal/1004 at connexur.dyndns.org) State HANGUP going to sleep
2011-12-03 17:29:00.815375 [DEBUG] switch_core_state_machine.c:393
(sofia/internal/1004 at connexur.dyndns.org) State Change CS_HANGUP ->
CS_REPORTING
2011-12-03 17:29:00.815375 [DEBUG] switch_core_session.c:1177 Send signal
sofia/internal/1004 at connexur.dyndns.org [BREAK]
2011-12-03 17:29:00.815375 [DEBUG] switch_core_state_machine.c:362
(sofia/internal/1004 at connexur.dyndns.org) Running State Change CS_REPORTING
2011-12-03 17:29:00.815375 [DEBUG] switch_core_state_machine.c:662
(sofia/internal/1004 at connexur.dyndns.org) State REPORTING
2011-12-03 17:29:00.815375 [DEBUG] switch_core_state_machine.c:79
sofia/internal/1004 at connexur.dyndns.org Standard REPORTING, cause:
NORMAL_CLEARING
2011-12-03 17:29:00.815375 [DEBUG] switch_core_state_machine.c:662
(sofia/internal/1004 at connexur.dyndns.org) State REPORTING going to sleep
2011-12-03 17:29:00.815375 [DEBUG] switch_core_state_machine.c:387
(sofia/internal/1004 at connexur.dyndns.org) State Change CS_REPORTING ->
CS_DESTROY
2011-12-03 17:29:00.815375 [DEBUG] switch_core_session.c:1177 Send signal
sofia/internal/1004 at connexur.dyndns.org [BREAK]
2011-12-03 17:29:00.815375 [DEBUG] switch_core_session.c:1377 Session 1
(sofia/internal/1004 at connexur.dyndns.org) Locked, Waiting on external
entities
2011-12-03 17:29:00.815375 [NOTICE] switch_core_session.c:1395 Session 1
(sofia/internal/1004 at connexur.dyndns.org) Ended
2011-12-03 17:29:00.815375 [NOTICE] switch_core_session.c:1397 Close Channel
sofia/internal/1004 at connexur.dyndns.org [CS_DESTROY]
2011-12-03 17:29:00.815375 [DEBUG] switch_core_state_machine.c:491
(sofia/internal/1004 at connexur.dyndns.org) Callstate Change HANGUP -> DOWN
2011-12-03 17:29:00.815375 [DEBUG] switch_core_state_machine.c:494
(sofia/internal/1004 at connexur.dyndns.org) Running State Change CS_DESTROY
2011-12-03 17:29:00.815375 [DEBUG] switch_core_state_machine.c:504
(sofia/internal/1004 at connexur.dyndns.org) State DESTROY
2011-12-03 17:29:00.815375 [DEBUG] mod_sofia.c:370
sofia/internal/1004 at connexur.dyndns.org SOFIA DESTROY
2011-12-03 17:29:00.815375 [DEBUG] switch_core_state_machine.c:86
sofia/internal/1004 at connexur.dyndns.org Standard DESTROY
2011-12-03 17:29:00.815375 [DEBUG] switch_core_state_machine.c:504
(sofia/internal/1004 at connexur.dyndns.org) State DESTROY going to sleep
2011-12-03 17:29:00.815375 [NOTICE] switch_core_session.c:1395 Session 2
(sofia/internal/sip:1001 at 192.168.0.43:5080) Ended
2011-12-03 17:29:00.815375 [NOTICE] switch_core_session.c:1397 Close Channel
sofia/internal/sip:1001 at 192.168.0.43:5080 [CS_DESTROY]
2011-12-03 17:29:00.815375 [DEBUG] switch_core_state_machine.c:491
(sofia/internal/sip:1001 at 192.168.0.43:5080) Callstate Change HANGUP -> DOWN
2011-12-03 17:29:00.815375 [DEBUG] switch_core_state_machine.c:494
(sofia/internal/sip:1001 at 192.168.0.43:5080) Running State Change CS_DESTROY
2011-12-03 17:29:00.815375 [DEBUG] switch_core_state_machine.c:504
(sofia/internal/sip:1001 at 192.168.0.43:5080) State DESTROY
2011-12-03 17:29:00.815375 [DEBUG] mod_sofia.c:370
sofia/internal/sip:1001 at 192.168.0.43:5080 SOFIA DESTROY
2011-12-03 17:29:00.815375 [DEBUG] switch_core_state_machine.c:86
sofia/internal/sip:1001 at 192.168.0.43:5080 Standard DESTROY
2011-12-03 17:29:00.815375 [DEBUG] switch_core_state_machine.c:504
(sofia/internal/sip:1001 at 192.168.0.43:5080) State DESTROY going to sleep

--
View this message in context: http://freeswitch-users.2379917.n2.nabble.com/Freeswitch-installed-ATA-registered-but-no-call-are-possible-tp7049935p7058216.html
Sent from the freeswitch-users mailing list archive at Nabble.com.