[Freeswitch-users] Not able to do outbound call

19 Jul 2010

      Hi to all,

I am very new to this world.  I am not able to do outbound call using
Sangoma A101 card  and freeSwitch.

Here is the procedure I have followed

*openzap.conf*

[span wanpipe smg_prid]
name => smg_prid
trunk_type =>e1
b-channel => 1:1-15
d-chennel => 1:16
b-channel => 1:17-31

I have one span in  *openzap.conf.xml*

<configuration name="openzap.conf" description="OpenZAP Configuration">
    <settings>
        <param name="debug" value="0"/>
    </settings>
<boot_spans>
   <span id="smg_prid"/>
       <param name="node" value="cpe"/>
       <param name="switch" value="ni1">
       <param name="dialplan" value="XML">
       <param name="context" value="default">
     </span>
 <boot_spans>
<analog_spans>
</analog_spans>
</configuration >
</analog_spans>

*My Dial plan is

*added one extension in default.xml file

<extension name="outgoing-pri">
        <condition field="destination_number" expression="12345">
                <action application="bridge" data="OpenZAP/smg_prid/a/<my
number>@g1"/>
        </condition>
</extension>

When i trying to call "12345" I am getting following output in *fs_cli* ,

2010-07-19 01:50:17.531933 [DEBUG] sofia.c:5992 IP 192.168.1.100 Rejected by
acl "domains". Falling back to Digest auth.
2010-07-19 01:50:17.562934 [DEBUG] sofia.c:5992 IP 192.168.1.100 Rejected by
acl "domains". Falling back to Digest auth.
2010-07-19 01:50:17.581933 [NOTICE] switch_channel.c:776 New Channel
sofia/internal/1000@192.168.1.110 [36c842f2-8078-4ca0-a6e0-36f5d65f81f8]
2010-07-19 01:50:17.583936 [DEBUG] switch_core_state_machine.c:314
(sofia/internal/1000@192.168.1.110) Running State Change CS_NEW
2010-07-19 01:50:17.583936 [DEBUG] switch_core_state_machine.c:320
(sofia/internal/1000@192.168.1.110) State NEW
2010-07-19 01:50:17.615937 [DEBUG] sofia.c:4310 Channel sofia/internal/
1000@192.168.1.110 entering state [received][100]
2010-07-19 01:50:17.615937 [DEBUG] sofia.c:4321 Remote SDP:
v=0
o=- 4 2 IN IP4 192.168.1.100
s=CounterPath X-Lite 3.0
c=IN IP4 192.168.1.100
t=0 0
m=audio 36422 RTP/AVP 107 0 8 101
a=rtpmap:107 BV32/16000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=alt:1 1 : hH//orIg hS6anlnf 192.168.1.100 36422

2010-07-19 01:50:17.615937 [DEBUG] sofia_glue.c:3859 Audio Codec Compare
[BV32:107:16000:20]/[G7221:115:32000:20]
2010-07-19 01:50:17.615937 [DEBUG] sofia_glue.c:3859 Audio Codec Compare
[BV32:107:16000:20]/[G7221:107:16000:20]
2010-07-19 01:50:17.615937 [DEBUG] sofia_glue.c:3859 Audio Codec Compare
[BV32:107:16000:20]/[G722:9:8000:20]
2010-07-19 01:50:17.615937 [DEBUG] sofia_glue.c:3859 Audio Codec Compare
[BV32:107:16000:20]/[PCMU:0:8000:20]
2010-07-19 01:50:17.615937 [DEBUG] sofia_glue.c:3859 Audio Codec Compare
[BV32:107:16000:20]/[PCMA:8:8000:20]
2010-07-19 01:50:17.615937 [DEBUG] sofia_glue.c:3859 Audio Codec Compare
[BV32:107:16000:20]/[GSM:3:8000:20]
2010-07-19 01:50:17.615937 [DEBUG] sofia_glue.c:3859 Audio Codec Compare
[PCMU:0:8000:20]/[G7221:115:32000:20]
2010-07-19 01:50:17.615937 [DEBUG] sofia_glue.c:3859 Audio Codec Compare
[PCMU:0:8000:20]/[G7221:107:16000:20]
2010-07-19 01:50:17.615937 [DEBUG] sofia_glue.c:3859 Audio Codec Compare
[PCMU:0:8000:20]/[G722:9:8000:20]
2010-07-19 01:50:17.615937 [DEBUG] sofia_glue.c:3859 Audio Codec Compare
[PCMU:0:8000:20]/[PCMU:0:8000:20]
2010-07-19 01:50:17.615937 [DEBUG] sofia_glue.c:2442 Set Codec
sofia/internal/1000@192.168.1.110 PCMU/8000 20 ms 160 samples
2010-07-19 01:50:17.615937 [DEBUG] sofia_glue.c:3798 Set 2833 dtmf send/recv
payload to 101
2010-07-19 01:50:17.615937 [DEBUG] sofia.c:4468 (sofia/internal/
1000@192.168.1.110) State Change CS_NEW -> CS_INIT
2010-07-19 01:50:17.615937 [DEBUG] switch_core_session.c:1027 Send signal
sofia/internal/1000@192.168.1.110 [BREAK]
2010-07-19 01:50:17.617033 [DEBUG] switch_core_state_machine.c:314
(sofia/internal/1000@192.168.1.110) Running State Change CS_INIT
2010-07-19 01:50:17.617033 [DEBUG] switch_core_state_machine.c:338
(sofia/internal/1000@192.168.1.110) State INIT
2010-07-19 01:50:17.617033 [DEBUG] mod_sofia.c:83 sofia/internal/
1000@192.168.1.110 SOFIA INIT
2010-07-19 01:50:17.617033 [DEBUG] mod_sofia.c:119 (sofia/internal/
1000@192.168.1.110) State Change CS_INIT -> CS_ROUTING
2010-07-19 01:50:17.617033 [DEBUG] switch_core_session.c:1027 Send signal
sofia/internal/1000@192.168.1.110 [BREAK]
2010-07-19 01:50:17.617033 [DEBUG] switch_core_state_machine.c:338
(sofia/internal/1000@192.168.1.110) State INIT going to sleep
2010-07-19 01:50:17.617033 [DEBUG] switch_core_state_machine.c:314
(sofia/internal/1000@192.168.1.110) Running State Change CS_ROUTING
2010-07-19 01:50:17.617033 [DEBUG] switch_channel.c:1471 (sofia/internal/
1000@192.168.1.110) Callstate Change DOWN -> RINGING
2010-07-19 01:50:17.617033 [DEBUG] switch_core_state_machine.c:341
(sofia/internal/1000@192.168.1.110) State ROUTING
2010-07-19 01:50:17.617033 [DEBUG] mod_sofia.c:142 sofia/internal/
1000@192.168.1.110 SOFIA ROUTING
2010-07-19 01:50:17.617033 [DEBUG] switch_core_state_machine.c:77
sofia/internal/1000@192.168.1.110 Standard ROUTING
2010-07-19 01:50:17.617033 [INFO] mod_dialplan_xml.c:331 Processing
1000->12345 in context default
Dialplan: sofia/internal/1000@192.168.1.110 parsing [default->unloop]
continue=false
Dialplan: sofia/internal/1000@192.168.1.110 Regex (PASS) [unloop]
${unroll_loops}(true) =~ /^true$/ break=on-false
Dialplan: sofia/internal/1000@192.168.1.110 Regex (FAIL) [unloop]
${sip_looped_call}() =~ /^true$/ break=on-false
Dialplan: sofia/internal/1000@192.168.1.110 parsing [default->outgoing-pri]
continue=false
Dialplan: sofia/internal/1000@192.168.1.110 Regex (PASS) [outgoing-pri]
destination_number(12345) =~ /12345/ break=on-false
Dialplan: sofia/internal/1000@192.168.1.110 Action
bridge(OpenZAP/smg_prid/a/09677012480@g1)
2010-07-19 01:50:17.618002 [DEBUG] switch_core_state_machine.c:119
(sofia/internal/1000@192.168.1.110) State Change CS_ROUTING -> CS_EXECUTE
2010-07-19 01:50:17.618002 [DEBUG] switch_core_session.c:1027 Send signal
sofia/internal/1000@192.168.1.110 [BREAK]
2010-07-19 01:50:17.618002 [DEBUG] switch_core_state_machine.c:341
(sofia/internal/1000@192.168.1.110) State ROUTING going to sleep
2010-07-19 01:50:17.618002 [DEBUG] switch_core_state_machine.c:314
(sofia/internal/1000@192.168.1.110) Running State Change CS_EXECUTE
2010-07-19 01:50:17.618002 [DEBUG] switch_core_state_machine.c:348
(sofia/internal/1000@192.168.1.110) State EXECUTE
2010-07-19 01:50:17.618002 [DEBUG] mod_sofia.c:235 sofia/internal/
1000@192.168.1.110 SOFIA EXECUTE
2010-07-19 01:50:17.618002 [DEBUG] switch_core_state_machine.c:157
sofia/internal/1000@192.168.1.110 Standard EXECUTE
EXECUTE sofia/internal/1000@192.168.1.110bridge(OpenZAP/smg_prid/a/09677012480@g1
)
2010-07-19 01:50:17.619035 [DEBUG] mod_openzap.c:403 Set codec PCMA 20ms
2010-07-19 01:50:17.619035 [DEBUG] mod_openzap.c:1317 Connect outbound
channel OpenZAP/1:1/09677012480@g1
2010-07-19 01:50:17.619035 [NOTICE] switch_channel.c:776 New Channel
OpenZAP/1:1/09677012480@g1 [36c14872-c8e1-4fa4-94ea-8c075d5133d2]
2010-07-19 01:50:17.619035 [DEBUG] mod_openzap.c:1331
(OpenZAP/1:1/09677012480@g1) State Change CS_NEW -> CS_INIT
2010-07-19 01:50:17.619035 [DEBUG] switch_core_session.c:1027 Send signal
OpenZAP/1:1/09677012480@g1 [BREAK]
2010-07-19 01:50:17.619035 [DEBUG] ozmod_analog.c:59 Changing state on 1:1
from DOWN to DIALING
2010-07-19 01:50:17.620937 [DEBUG] ozmod_analog.c:293 ANALOG CHANNEL thread
starting.
2010-07-19 01:50:17.620937 [DEBUG] ozmod_analog.c:464 Executing state
handler on 1:1 for DIALING
2010-07-19 01:50:17.633931 [DEBUG] switch_core_state_machine.c:314
(OpenZAP/1:1/09677012480@g1) Running State Change CS_INIT
2010-07-19 01:50:17.633931 [DEBUG] switch_core_state_machine.c:338
(OpenZAP/1:1/09677012480@g1) State INIT
2010-07-19 01:50:17.633931 [DEBUG] mod_openzap.c:431
(OpenZAP/1:1/09677012480@g1) State Change CS_INIT -> CS_ROUTING
2010-07-19 01:50:17.633931 [DEBUG] switch_core_session.c:1027 Send signal
OpenZAP/1:1/09677012480@g1 [BREAK]
2010-07-19 01:50:17.633931 [DEBUG] switch_core_state_machine.c:338
(OpenZAP/1:1/09677012480@g1) State INIT going to sleep
2010-07-19 01:50:17.633931 [DEBUG] switch_core_state_machine.c:314
(OpenZAP/1:1/09677012480@g1) Running State Change CS_ROUTING
2010-07-19 01:50:17.633931 [DEBUG] switch_channel.c:1471
(OpenZAP/1:1/09677012480@g1) Callstate Change DOWN -> RINGING
2010-07-19 01:50:17.633931 [DEBUG] switch_core_state_machine.c:341
(OpenZAP/1:1/09677012480@g1) State ROUTING
2010-07-19 01:50:17.633931 [DEBUG] mod_openzap.c:454
OpenZAP/1:1/09677012480@g1 CHANNEL ROUTING
2010-07-19 01:50:17.633931 [DEBUG] switch_ivr_originate.c:64
(OpenZAP/1:1/09677012480@g1) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2010-07-19 01:50:17.633931 [DEBUG] switch_core_session.c:1027 Send signal
OpenZAP/1:1/09677012480@g1 [BREAK]
2010-07-19 01:50:17.633931 [DEBUG] switch_core_state_machine.c:341
(OpenZAP/1:1/09677012480@g1) State ROUTING going to sleep
2010-07-19 01:50:17.633931 [DEBUG] switch_core_state_machine.c:314
(OpenZAP/1:1/09677012480@g1) Running State Change CS_CONSUME_MEDIA
2010-07-19 01:50:17.633931 [DEBUG] switch_core_state_machine.c:360
(OpenZAP/1:1/09677012480@g1) State CONSUME_MEDIA
2010-07-19 01:50:17.633931 [DEBUG] switch_core_state_machine.c:360
(OpenZAP/1:1/09677012480@g1) State CONSUME_MEDIA going to sleep
2010-07-19 01:50:47.638427 [DEBUG] ozmod_analog.c:354 Changing state on 1:1
from DIALING to BUSY
2010-07-19 01:50:47.658427 [DEBUG] ozmod_analog.c:464 Executing state
handler on 1:1 for BUSY
2010-07-19 01:50:47.658427 [DEBUG] ozmod_analog.c:593 Changing state on 1:1
from BUSY to DOWN
2010-07-19 01:50:47.678427 [DEBUG] ozmod_analog.c:464 Executing state
handler on 1:1 for DOWN
2010-07-19 01:50:47.678427 [WARNING] mod_openzap.c:2038 Unhandled analog
channel type 0 for channel 1:1
2010-07-19 01:50:47.678427 [DEBUG] zap_io.c:1388 channel done 1:1
2010-07-19 01:50:47.678427 [DEBUG] ozmod_analog.c:778 ANALOG CHANNEL 1:1
thread ended.
2010-07-19 01:51:16.667683 [DEBUG] sofia.c:4310 Channel sofia/internal/
1000@192.168.1.110 entering state [terminated][487]
2010-07-19 01:51:16.667683 [DEBUG] switch_channel.c:2261 (sofia/internal/
1000@192.168.1.110) Callstate Change RINGING -> HANGUP
2010-07-19 01:51:16.667683 [NOTICE] sofia.c:4924 Hangup sofia/internal/
1000@192.168.1.110 [CS_EXECUTE] [ORIGINATOR_CANCEL]
2010-07-19 01:51:16.667683 [DEBUG] switch_channel.c:2277 Send signal
sofia/internal/1000@192.168.1.110 [KILL]
2010-07-19 01:51:16.667683 [DEBUG] switch_core_session.c:1027 Send signal
sofia/internal/1000@192.168.1.110 [BREAK]
2010-07-19 01:51:16.667683 [DEBUG] switch_channel.c:2261
(OpenZAP/1:1/09677012480@g1) Callstate Change RINGING -> HANGUP
2010-07-19 01:51:16.667683 [NOTICE] switch_ivr_originate.c:3220 Hangup
OpenZAP/1:1/09677012480@g1 [CS_CONSUME_MEDIA] [ORIGINATOR_CANCEL]
2010-07-19 01:51:16.667683 [DEBUG] switch_channel.c:2277 Send signal
OpenZAP/1:1/09677012480@g1 [KILL]
2010-07-19 01:51:16.667683 [DEBUG] switch_core_session.c:1027 Send signal
OpenZAP/1:1/09677012480@g1 [BREAK]
2010-07-19 01:51:16.667683 [DEBUG] switch_ivr_originate.c:3363 Originate
Cancelled by originator termination Cause: 487 [ORIGINATOR_CANCEL]
2010-07-19 01:51:16.667683 [INFO] mod_dptools.c:2382 Originate Failed.
Cause: ORIGINATOR_CANCEL
2010-07-19 01:51:16.667683 [DEBUG] switch_core_state_machine.c:314
(OpenZAP/1:1/09677012480@g1) Running State Change CS_HANGUP
2010-07-19 01:51:16.669919 [DEBUG] switch_core_state_machine.c:348
(sofia/internal/1000@192.168.1.110) State EXECUTE going to sleep
2010-07-19 01:51:16.669919 [DEBUG] switch_core_state_machine.c:314
(sofia/internal/1000@192.168.1.110) Running State Change CS_HANGUP
2010-07-19 01:51:16.669919 [DEBUG] switch_core_state_machine.c:500
(OpenZAP/1:1/09677012480@g1) State HANGUP
2010-07-19 01:51:16.669919 [DEBUG] mod_openzap.c:556
OpenZAP/1:1/09677012480@g1 CHANNEL HANGUP
2010-07-19 01:51:16.669919 [DEBUG] switch_core_state_machine.c:46
OpenZAP/1:1/09677012480@g1 Standard HANGUP, cause: ORIGINATOR_CANCEL
2010-07-19 01:51:16.669919 [DEBUG] switch_core_state_machine.c:500
(OpenZAP/1:1/09677012480@g1) State HANGUP going to sleep
2010-07-19 01:51:16.669919 [DEBUG] switch_core_state_machine.c:333
(OpenZAP/1:1/09677012480@g1) State Change CS_HANGUP -> CS_REPORTING
2010-07-19 01:51:16.669919 [DEBUG] switch_core_session.c:1027 Send signal
OpenZAP/1:1/09677012480@g1 [BREAK]
2010-07-19 01:51:16.669919 [DEBUG] switch_core_state_machine.c:314
(OpenZAP/1:1/09677012480@g1) Running State Change CS_REPORTING
2010-07-19 01:51:16.669919 [DEBUG] switch_core_state_machine.c:591
(OpenZAP/1:1/09677012480@g1) State REPORTING
2010-07-19 01:51:16.669919 [DEBUG] switch_core_state_machine.c:53
OpenZAP/1:1/09677012480@g1 Standard REPORTING, cause: ORIGINATOR_CANCEL
2010-07-19 01:51:16.669919 [DEBUG] switch_core_state_machine.c:591
(OpenZAP/1:1/09677012480@g1) State REPORTING going to sleep
2010-07-19 01:51:16.669919 [DEBUG] switch_core_state_machine.c:327
(OpenZAP/1:1/09677012480@g1) State Change CS_REPORTING -> CS_DESTROY
2010-07-19 01:51:16.669919 [DEBUG] switch_core_session.c:1027 Send signal
OpenZAP/1:1/09677012480@g1 [BREAK]
2010-07-19 01:51:16.669919 [DEBUG] switch_core_session.c:1175 Session 33
(OpenZAP/1:1/09677012480@g1) Locked, Waiting on external entities
2010-07-19 01:51:16.669919 [NOTICE] switch_core_session.c:1193 Session 33
(OpenZAP/1:1/09677012480@g1) Ended
2010-07-19 01:51:16.669919 [NOTICE] switch_core_session.c:1195 Close Channel
OpenZAP/1:1/09677012480@g1 [CS_DESTROY]
2010-07-19 01:51:16.669919 [DEBUG] switch_core_state_machine.c:500
(sofia/internal/1000@192.168.1.110) State HANGUP
2010-07-19 01:51:16.669919 [DEBUG] mod_sofia.c:449 sofia/internal/
1000@192.168.1.110 Overriding SIP cause 487 with 487 from the other leg
2010-07-19 01:51:16.669919 [DEBUG] mod_sofia.c:455 Channel sofia/internal/
1000@192.168.1.110 hanging up, cause: ORIGINATOR_CANCEL
2010-07-19 01:51:16.669919 [DEBUG] switch_core_state_machine.c:427
(OpenZAP/1:1/09677012480@g1) Callstate Change HANGUP -> DOWN
2010-07-19 01:51:16.669919 [DEBUG] switch_core_state_machine.c:430
(OpenZAP/1:1/09677012480@g1) Running State Change CS_DESTROY
2010-07-19 01:51:16.669919 [DEBUG] switch_core_state_machine.c:440
(OpenZAP/1:1/09677012480@g1) State DESTROY
2010-07-19 01:51:16.669919 [DEBUG] switch_core_state_machine.c:60
OpenZAP/1:1/09677012480@g1 Standard DESTROY
2010-07-19 01:51:16.669919 [DEBUG] switch_core_state_machine.c:440
(OpenZAP/1:1/09677012480@g1) State DESTROY going to sleep
2010-07-19 01:51:16.699683 [DEBUG] switch_core_state_machine.c:46
sofia/internal/1000@192.168.1.110 Standard HANGUP, cause: ORIGINATOR_CANCEL
2010-07-19 01:51:16.699683 [DEBUG] switch_core_state_machine.c:500
(sofia/internal/1000@192.168.1.110) State HANGUP going to sleep
2010-07-19 01:51:16.701682 [DEBUG] switch_core_state_machine.c:333
(sofia/internal/1000@192.168.1.110) State Change CS_HANGUP -> CS_REPORTING
2010-07-19 01:51:16.701682 [DEBUG] switch_core_session.c:1027 Send signal
sofia/internal/1000@192.168.1.110 [BREAK]
2010-07-19 01:51:16.701682 [DEBUG] switch_core_state_machine.c:314
(sofia/internal/1000@192.168.1.110) Running State Change CS_REPORTING
2010-07-19 01:51:16.701682 [DEBUG] switch_core_state_machine.c:591
(sofia/internal/1000@192.168.1.110) State REPORTING
2010-07-19 01:51:16.701682 [DEBUG] switch_core_state_machine.c:53
sofia/internal/1000@192.168.1.110 Standard REPORTING, cause:
ORIGINATOR_CANCEL
2010-07-19 01:51:16.701682 [DEBUG] switch_core_state_machine.c:591
(sofia/internal/1000@192.168.1.110) State REPORTING going to sleep
2010-07-19 01:51:16.701682 [DEBUG] switch_core_state_machine.c:327
(sofia/internal/1000@192.168.1.110) State Change CS_REPORTING -> CS_DESTROY
2010-07-19 01:51:16.701682 [DEBUG] switch_core_session.c:1027 Send signal
sofia/internal/1000@192.168.1.110 [BREAK]
2010-07-19 01:51:16.701682 [DEBUG] switch_core_session.c:1175 Session 32
(sofia/internal/1000@192.168.1.110) Locked, Waiting on external entities
2010-07-19 01:51:16.701682 [NOTICE] switch_core_session.c:1193 Session 32
(sofia/internal/1000@192.168.1.110) Ended
2010-07-19 01:51:16.701682 [NOTICE] switch_core_session.c:1195 Close Channel
sofia/internal/1000@192.168.1.110 [CS_DESTROY]
2010-07-19 01:51:16.701682 [DEBUG] switch_core_state_machine.c:427
(sofia/internal/1000@192.168.1.110) Callstate Change HANGUP -> DOWN
2010-07-19 01:51:16.701682 [DEBUG] switch_core_state_machine.c:430
(sofia/internal/1000@192.168.1.110) Running State Change CS_DESTROY
2010-07-19 01:51:16.701682 [DEBUG] switch_core_state_machine.c:440
(sofia/internal/1000@192.168.1.110) State DESTROY
2010-07-19 01:51:16.701682 [DEBUG] mod_sofia.c:360 sofia/internal/
1000@192.168.1.110 SOFIA DESTROY
2010-07-19 01:51:16.701682 [DEBUG] switch_core_state_machine.c:60
sofia/internal/1000@192.168.1.110 Standard DESTROY
2010-07-19 01:51:16.701682 [DEBUG] switch_core_state_machine.c:440
(sofia/internal/1000@192.168.1.110) State DESTROY going to sleep

Thanks in advance,

Regards,
Venkat.

[Freeswitch-users] Not able to do outbound call

ovvenkat