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,

Some info:

Freetdm.conf:

[span zt OIS]

trunk_type => EM

em-channel => 1-24

freetdm.conf.xml:

<analog_em_spans>

</span>

</analog_em_spans>

Dialplan:

</condition>

</extension>

</context>

freeswitch@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@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@internal> bgapi originate freetdm/1/1 200

+OK Job-UUID: 603fb938-e214-11e4-97e6-43b5b7de6600

freeswitch@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