[Freeswitch-users] Auto answer for FreeTDM analog_em

DAVIS, JONATHAN P. (KSC-ITD10) jonathan.p.davis at nasa.gov
Mon Apr 13 23:43:30 MSD 2015 

Hey all,

I am creating a WebRTC interface for work that interfaces to our legacy intercom system. I have set up my span in FreeTDM as analog_em and have green lights all around for signaling.

I need to be able to originate the DS0 into a conference, but attempting to do so causes the extension to ring for about 30 sec before finally going from DIALING to BUSY. I believe the endpoint isn't being answered. I had this working successfully in Asterisk by setting immediate=yes in chan_dahdi.conf. I found sip_auto_answer, and immediate_accept in the open_r2 module, but nothing for analog_em. I have nothing that would let me configure this on my conferencing switch, but believe there must be a way to configure it from my side as Asterisk could do it. Any help is greatly appreciated.

Thanks,
JD

Some info:

Freetdm.conf:
[span zt OIS]
trunk_type => EM
em-channel => 1-24

freetdm.conf.xml:
<analog_em_spans>
                <span name="OIS">
                        <param name="dialplan" value="XML"/>
                        <param name="context" value="basic"/>
                </span>
</analog_em_spans>

Dialplan:
<context name="basic">
        <extension name="conf1">
                <condition field="destination_number" expression="^200$">
                        <app name="answer"/>
                        <action application="conference" data="conf1"/>
                </condition>
        </extension>
</context>

freeswitch at internal> ftdm list
+OK
span: 1 (OIS)
type: analog_em
physical_status: ok
signaling_status: UP
chan_count: 24
dialplan: XML
context: basic
dial_regex:
fail_dial_regex:
hold_music:
analog_options: none

freeswitch at internal> ftdm dump 1 1
span_id: 1
chan_id: 1
physical_span_id: 1
physical_chan_id: 1
physical_status: ok
physical_status_red: 0
physical_status_yellow: 0
physical_status_rai: 0
physical_status_blue: 0
physical_status_ais: 0
physical_status_general: 0
signaling_status: UP
type: EM
state: DOWN
last_state: DOWN
txgain: 0.00
rxgain: 0.00
cid_date:
cid_name:
cid_num:
ani:
aniII:
dnis:
rdnis:
cause: NONE
session: (none)

-- No state history -

freeswitch at internal> bgapi originate freetdm/1/1 200
+OK Job-UUID: 603fb938-e214-11e4-97e6-43b5b7de6600

freeswitch at internal> 2015-04-13 15:36:26.979819 [DEBUG] switch_ivr_originate.c:2100 Parsing global variables
2015-04-13 15:36:26.979819 [INFO] ftmod_zt.c:671 Setting echo cancel to 64 taps for 1:1
2015-04-13 15:36:26.979819 [DEBUG] mod_freetdm.c:403 Set codec PCMU 20ms
2015-04-13 15:36:26.979819 [DEBUG] mod_freetdm.c:1251 Connect outbound channel FreeTDM/1:1/
2015-04-13 15:36:26.979819 [NOTICE] switch_channel.c:1055 New Channel FreeTDM/1:1/ [603fc6f8-e214-11e4-97e8-43b5b7de6600]
2015-04-13 15:36:26.979819 [DEBUG] mod_freetdm.c:1260 (FreeTDM/1:1/) State Change CS_NEW -> CS_INIT
2015-04-13 15:36:26.979819 [DEBUG] switch_core_session.c:1396 Send signal FreeTDM/1:1/ [BREAK]
2015-04-13 15:36:26.979819 [DEBUG] mod_freetdm.c:1279 Attached session 603fc6f8-e214-11e4-97e8-43b5b7de6600 to channel 1:1
2015-04-13 15:36:26.979819 [DEBUG] ftmod_zt.c:754 [s1c1][1:1] Channel is now offhook
2015-04-13 15:36:26.979819 [DEBUG] ftmod_analog_em.c:168 [s1c1][1:1] Changed state from DOWN to DIALING
2015-04-13 15:36:26.979819 [DEBUG] ftmod_analog_em.c:528 ANALOG EM CHANNEL thread starting.
2015-04-13 15:36:26.979819 [INFO] ftmod_zt.c:671 Setting echo cancel to 64 taps for 1:1
2015-04-13 15:36:26.999821 [DEBUG] ftdm_io.c:3395 [s1c1][1:1] Enabled software DTMF detector
2015-04-13 15:36:26.999821 [DEBUG] ftmod_analog_em.c:565 [s1c1][1:1] IO Interval: 20
2015-04-13 15:36:26.999821 [DEBUG] ftmod_analog_em.c:708 [s1c1][1:1] Completed state change from DOWN to DIALING in 1 ms
2015-04-13 15:36:26.999821 [DEBUG] ftmod_analog_em.c:712 Executing state handler on 1:1 for DIALING
2015-04-13 15:36:27.479818 [DEBUG] switch_core_state_machine.c:472 (FreeTDM/1:1/) Running State Change CS_INIT
2015-04-13 15:36:27.479818 [DEBUG] switch_core_state_machine.c:512 (FreeTDM/1:1/) State INIT
2015-04-13 15:36:27.479818 [DEBUG] mod_freetdm.c:430 (FreeTDM/1:1/) State Change CS_INIT -> CS_ROUTING
2015-04-13 15:36:27.479818 [DEBUG] switch_core_session.c:1396 Send signal FreeTDM/1:1/ [BREAK]
2015-04-13 15:36:27.479818 [DEBUG] switch_core_state_machine.c:512 (FreeTDM/1:1/) State INIT going to sleep
2015-04-13 15:36:27.479818 [DEBUG] switch_core_state_machine.c:472 (FreeTDM/1:1/) Running State Change CS_ROUTING
2015-04-13 15:36:27.479818 [DEBUG] switch_core_state_machine.c:528 (FreeTDM/1:1/) State ROUTING
2015-04-13 15:36:27.479818 [DEBUG] mod_freetdm.c:453 FreeTDM/1:1/ CHANNEL ROUTING
2015-04-13 15:36:27.479818 [DEBUG] switch_ivr_originate.c:67 (FreeTDM/1:1/) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2015-04-13 15:36:27.479818 [DEBUG] switch_core_session.c:1396 Send signal FreeTDM/1:1/ [BREAK]
2015-04-13 15:36:27.479818 [DEBUG] switch_core_state_machine.c:528 (FreeTDM/1:1/) State ROUTING going to sleep
2015-04-13 15:36:27.479818 [DEBUG] switch_core_state_machine.c:472 (FreeTDM/1:1/) Running State Change CS_CONSUME_MEDIA
2015-04-13 15:36:27.479818 [DEBUG] switch_core_state_machine.c:547 (FreeTDM/1:1/) State CONSUME_MEDIA
2015-04-13 15:36:27.479818 [DEBUG] switch_core_state_machine.c:547 (FreeTDM/1:1/) State CONSUME_MEDIA going to sleep
2015-04-13 15:36:27.979820 [DEBUG] ftmod_analog_em.c:628 [s1c1][1:1] CAS bits: 0x0
2015-04-13 15:36:28.979821 [DEBUG] ftmod_analog_em.c:628 [s1c1][1:1] CAS bits: 0x0
2015-04-13 15:36:29.979820 [DEBUG] ftmod_analog_em.c:628 [s1c1][1:1] CAS bits: 0x0
2015-04-13 15:36:30.979820 [DEBUG] ftmod_analog_em.c:628 [s1c1][1:1] CAS bits: 0x0
2015-04-13 15:36:31.979820 [DEBUG] ftmod_analog_em.c:628 [s1c1][1:1] CAS bits: 0x0
2015-04-13 15:36:32.979818 [DEBUG] ftmod_analog_em.c:628 [s1c1][1:1] CAS bits: 0x0
2015-04-13 15:36:33.979821 [DEBUG] ftmod_analog_em.c:628 [s1c1][1:1] CAS bits: 0x0
2015-04-13 15:36:34.979821 [DEBUG] ftmod_analog_em.c:628 [s1c1][1:1] CAS bits: 0x0
2015-04-13 15:36:35.979821 [DEBUG] ftmod_analog_em.c:628 [s1c1][1:1] CAS bits: 0x0
2015-04-13 15:36:36.979821 [DEBUG] ftmod_analog_em.c:628 [s1c1][1:1] CAS bits: 0x0
2015-04-13 15:36:37.979820 [DEBUG] ftmod_analog_em.c:628 [s1c1][1:1] CAS bits: 0x0
2015-04-13 15:36:38.979821 [DEBUG] ftmod_analog_em.c:628 [s1c1][1:1] CAS bits: 0x0
2015-04-13 15:36:39.979819 [DEBUG] ftmod_analog_em.c:628 [s1c1][1:1] CAS bits: 0x0
2015-04-13 15:36:40.979819 [DEBUG] ftmod_analog_em.c:628 [s1c1][1:1] CAS bits: 0x0
2015-04-13 15:36:41.979819 [DEBUG] ftmod_analog_em.c:628 [s1c1][1:1] CAS bits: 0x0
2015-04-13 15:36:42.979819 [DEBUG] ftmod_analog_em.c:628 [s1c1][1:1] CAS bits: 0x0
2015-04-13 15:36:43.979820 [DEBUG] ftmod_analog_em.c:628 [s1c1][1:1] CAS bits: 0x0
2015-04-13 15:36:44.979820 [DEBUG] ftmod_analog_em.c:628 [s1c1][1:1] CAS bits: 0x0
2015-04-13 15:36:45.979820 [DEBUG] ftmod_analog_em.c:628 [s1c1][1:1] CAS bits: 0x0
2015-04-13 15:36:46.979820 [DEBUG] ftmod_analog_em.c:628 [s1c1][1:1] CAS bits: 0x0
2015-04-13 15:36:47.979820 [DEBUG] ftmod_analog_em.c:628 [s1c1][1:1] CAS bits: 0x0
2015-04-13 15:36:48.979819 [DEBUG] ftmod_analog_em.c:628 [s1c1][1:1] CAS bits: 0x0
2015-04-13 15:36:49.979818 [DEBUG] ftmod_analog_em.c:628 [s1c1][1:1] CAS bits: 0x0
2015-04-13 15:36:50.979820 [DEBUG] ftmod_analog_em.c:628 [s1c1][1:1] CAS bits: 0x0
2015-04-13 15:36:51.979819 [DEBUG] ftmod_analog_em.c:628 [s1c1][1:1] CAS bits: 0x0
2015-04-13 15:36:52.979820 [DEBUG] ftmod_analog_em.c:628 [s1c1][1:1] CAS bits: 0x0
2015-04-13 15:36:53.979819 [DEBUG] ftmod_analog_em.c:628 [s1c1][1:1] CAS bits: 0x0
2015-04-13 15:36:54.979820 [DEBUG] ftmod_analog_em.c:628 [s1c1][1:1] CAS bits: 0x0
2015-04-13 15:36:55.979820 [DEBUG] ftmod_analog_em.c:628 [s1c1][1:1] CAS bits: 0x0
2015-04-13 15:36:56.979820 [DEBUG] ftmod_analog_em.c:628 [s1c1][1:1] CAS bits: 0x0
2015-04-13 15:36:56.999820 [DEBUG] ftmod_analog_em.c:620 [s1c1][1:1] Changed state from DIALING to BUSY
2015-04-13 15:36:57.019820 [DEBUG] ftmod_analog_em.c:708 [s1c1][1:1] Completed state change from DIALING to BUSY in 20 ms
2015-04-13 15:36:57.019820 [DEBUG] ftmod_analog_em.c:712 Executing state handler on 1:1 for BUSY
2015-04-13 15:36:57.019820 [DEBUG] ftmod_analog_em.c:789 [s1c1][1:1] Changed state from BUSY to DOWN
2015-04-13 15:36:57.039820 [DEBUG] ftmod_analog_em.c:708 [s1c1][1:1] Completed state change from BUSY to DOWN in 20 ms
2015-04-13 15:36:57.039820 [DEBUG] ftmod_analog_em.c:712 Executing state handler on 1:1 for DOWN
2015-04-13 15:36:57.039820 [DEBUG] mod_freetdm.c:2262 got FXO sig 1:1 [STOP]
2015-04-13 15:36:57.039820 [NOTICE] mod_freetdm.c:2282 Hangup FreeTDM/1:1/ [CS_CONSUME_MEDIA] [NORMAL_CIRCUIT_CONGESTION]
2015-04-13 15:36:57.039820 [DEBUG] switch_channel.c:3222 Send signal FreeTDM/1:1/ [KILL]
2015-04-13 15:36:57.039820 [DEBUG] switch_core_session.c:1396 Send signal FreeTDM/1:1/ [BREAK]
2015-04-13 15:36:57.039820 [DEBUG] ftmod_zt.c:765 [s1c1][1:1] Channel is now onhook
2015-04-13 15:36:57.039820 [DEBUG] switch_core_state_machine.c:472 (FreeTDM/1:1/) Running State Change CS_HANGUP
2015-04-13 15:36:57.039820 [DEBUG] ftdm_io.c:3194 [s1c1][1:1] DTMF debug is already disabled
2015-04-13 15:36:57.039820 [DEBUG] ftdm_io.c:3226 [s1c1][1:1] No need to disable input dump
2015-04-13 15:36:57.039820 [DEBUG] ftdm_io.c:3257 [s1c1][1:1] No need to disable output dump
2015-04-13 15:36:57.039820 [DEBUG] ftdm_io.c:6873 Cleared call with id 2
2015-04-13 15:36:57.039820 [DEBUG] ftdm_io.c:2999 [s1c1][1:1] channel done
2015-04-13 15:36:57.039820 [DEBUG] ftmod_analog_em.c:993 ANALOG EM CHANNEL thread ended.
2015-04-13 15:36:57.039820 [DEBUG] switch_core_state_machine.c:735 (FreeTDM/1:1/) Callstate Change DOWN -> HANGUP
2015-04-13 15:36:57.039820 [DEBUG] switch_core_state_machine.c:737 (FreeTDM/1:1/) State HANGUP
2015-04-13 15:36:57.039820 [DEBUG] mod_freetdm.c:544 [1:1] FreeTDM/1:1/ CHANNEL HANGUP ENTER
2015-04-13 15:36:57.039820 [DEBUG] mod_freetdm.c:569 Device [1:1] is no longer attached to FreeTDM/1:1/
2015-04-13 15:36:57.039820 [DEBUG] mod_freetdm.c:643 [1:1] FreeTDM/1:1/ CHANNEL HANGUP EXIT
2015-04-13 15:36:57.039820 [DEBUG] switch_core_state_machine.c:60 FreeTDM/1:1/ Standard HANGUP, cause: NORMAL_CIRCUIT_CONGESTION
2015-04-13 15:36:57.039820 [DEBUG] switch_core_state_machine.c:737 (FreeTDM/1:1/) State HANGUP going to sleep
2015-04-13 15:36:57.039820 [DEBUG] switch_core_state_machine.c:504 (FreeTDM/1:1/) State Change CS_HANGUP -> CS_REPORTING
2015-04-13 15:36:57.039820 [DEBUG] switch_core_session.c:1396 Send signal FreeTDM/1:1/ [BREAK]
2015-04-13 15:36:57.039820 [DEBUG] switch_core_state_machine.c:472 (FreeTDM/1:1/) Running State Change CS_REPORTING
2015-04-13 15:36:57.039820 [DEBUG] switch_core_state_machine.c:823 (FreeTDM/1:1/) State REPORTING
2015-04-13 15:36:57.039820 [DEBUG] switch_core_state_machine.c:104 FreeTDM/1:1/ Standard REPORTING, cause: NORMAL_CIRCUIT_CONGESTION
2015-04-13 15:36:57.039820 [DEBUG] switch_core_state_machine.c:823 (FreeTDM/1:1/) State REPORTING going to sleep
2015-04-13 15:36:57.039820 [DEBUG] switch_core_state_machine.c:498 (FreeTDM/1:1/) State Change CS_REPORTING -> CS_DESTROY
2015-04-13 15:36:57.039820 [DEBUG] switch_core_session.c:1396 Send signal FreeTDM/1:1/ [BREAK]
2015-04-13 15:36:57.039820 [DEBUG] switch_core_session.c:1623 Session 2 (FreeTDM/1:1/) Locked, Waiting on external entities
2015-04-13 15:36:57.059821 [DEBUG] switch_ivr_originate.c:3720 Originate Resulted in Error Cause: 34 [NORMAL_CIRCUIT_CONGESTION]
2015-04-13 15:36:57.059821 [NOTICE] switch_core_session.c:1641 Session 2 (FreeTDM/1:1/) Ended
2015-04-13 15:36:57.059821 [NOTICE] switch_core_session.c:1645 Close Channel FreeTDM/1:1/ [CS_DESTROY]
2015-04-13 15:36:57.059821 [DEBUG] switch_core_state_machine.c:626 (FreeTDM/1:1/) Running State Change CS_DESTROY
2015-04-13 15:36:57.059821 [DEBUG] switch_core_state_machine.c:636 (FreeTDM/1:1/) State DESTROY
2015-04-13 15:36:57.059821 [DEBUG] switch_core_state_machine.c:111 FreeTDM/1:1/ Standard DESTROY
2015-04-13 15:36:57.059821 [DEBUG] switch_core_state_machine.c:636 (FreeTDM/1:1/) State DESTROY going to sleep
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20150413/49794e53/attachment-0001.html

Join us at ClueCon 2016 Aug 8-12, 2016
More information about the FreeSWITCH-users mailing list