[Freeswitch-users] music on hold problem with Sangoma A200 board on FS

Tamer Higazi th982a at googlemail.com
Sun Jun 30 22:28:54 MSD 2013 

Hi people!
I am running FreeSWITCH Version 1.5.2b+git~20130618T211908Z~9cf05e3595
(git 9cf05e3 2013-06-18 21:19:08Z) and I have a problem with music on
hold with my Sangoma A200 board, and I don't know how to solve it.

Everytime, the other party puts me on hold, I don't hear anything and
the connection is dropped. Any ideas?!

For any ideas solving this problem, I woudl kindly thank you.


Tamer


My freetdm.conf.xml looks like this:

configuration name="freetdm.conf" description="Freetdm Configuration">
  <settings>
    <param name="debug" value="0"/>
     <param name="hold-music" value="$${hold_music}"/>
    <param name="enable-analog-option" value="call-swap"/>
    <param name="enable-analog-option" value="3-way"/>
  </settings>       
<config_profiles>
</config_profiles>
<analog_spans>
        <span name="FXS">
            <!--<param name="hold-music" value="$${moh_uri}"/>-->
            <param name="dialplan" value="XML"/>
            <param name="context" value="default"/>
             <!-- regex to stop dialing when it matches -->
            <!--<param name="dial-regex" value="5555"/>-->
            <!-- regex to stop dialing when it does not match -->
            <!--<param name="fail-dial-regex" value="^5"/>-->
        </span>
    </analog_spans>
</configuration>


and the console output is here:

2013-06-27 16:44:13.286747 [DEBUG] switch_channel.c:1746
(sofia/external/061312000) Callstate Change ACTIVE -> HELD
2013-06-27 16:44:13.286747 [DEBUG] switch_core_session.c:1141 Send
signal FreeTDM/1:1/0061312000 [BREAK]
2013-06-27 16:44:13.286747 [DEBUG] switch_core_session.c:861 Send signal
FreeTDM/1:1/0061312000 [BREAK]
2013-06-27 16:44:13.336605 [DEBUG] switch_ivr.c:612
FreeTDM/1:1/0061312000 Command Execute playback(local_stream://moh)
EXECUTE FreeTDM/1:1/0061312000 playback(local_stream://moh)
2013-06-27 16:44:13.336605 [WARNING] mod_local_stream.c:469 Unknown
source moh, trying 'default'
2013-06-27 16:44:13.336605 [ERR] mod_local_stream.c:478 Unknown source
default
2013-06-27 16:44:13.336605 [DEBUG] switch_core_session.c:861 Send signal
FreeTDM/1:1/0061312000 [BREAK]
2013-06-27 16:44:13.336605 [DEBUG] switch_ivr_bridge.c:388 Send signal
sofia/external/061312000 [BREAK]
2013-06-27 16:44:13.536666 [DEBUG] switch_core_media.c:2723 Audio Codec
Compare [PCMA:8:8000:20:64000]/[PCMA:8:8000:10:64000]
2013-06-27 16:44:13.536666 [DEBUG] switch_core_media.c:1748 Already
using PCMA
2013-06-27 16:44:13.536666 [DEBUG] switch_core_media.c:2885 Set 2833
dtmf send payload to 101
2013-06-27 16:44:13.536666 [DEBUG] switch_core_media.c:3885 Audio params
are unchanged for sofia/external/061312000.
2013-06-27 16:44:13.536666 [DEBUG] switch_core_media.c:3895
sofia/external/061312000 Setting audio receive payload in Re-INVITE to 8
2013-06-27 16:44:13.536666 [DEBUG] sofia.c:6286 Processing updated SDP
2013-06-27 16:44:13.536666 [DEBUG] switch_core_session.c:1006 Send
signal sofia/external/061312000 [BREAK]
2013-06-27 16:44:13.546766 [DEBUG] sofia.c:5745 Channel
sofia/external/061312000 entering state [completed][200]
2013-06-27 16:44:13.606614 [DEBUG] switch_core_session.c:1006 Send
signal sofia/external/061312000 [BREAK]
2013-06-27 16:44:13.606614 [DEBUG] switch_core_session.c:1006 Send
signal sofia/external/061312000 [BREAK]
2013-06-27 16:44:13.606614 [DEBUG] switch_core_session.c:1006 Send
signal sofia/external/061312000 [BREAK]
2013-06-27 16:44:13.616646 [DEBUG] sofia.c:5745 Channel
sofia/external/061312000 entering state [ready][200]
2013-06-27 16:44:23.186805 [DEBUG] ftmod_wanpipe.c:1678 [s1c1][1:3] read
wanpipe event 5
2013-06-27 16:44:23.186805 [DEBUG] ftmod_wanpipe.c:1462 [s1c1][1:3] Got
wanpipe ONHOOK
2013-06-27 16:44:23.186805 [DEBUG] ftmod_wanpipe.c:1685 [s1c1][1:3]
Ignoring event for now
2013-06-27 16:44:23.936625 [DEBUG] ftmod_wanpipe.c:1661 [s1c1][1:3] Diff
since last event = 752 ms, delivering ONHOOK now
2013-06-27 16:44:23.936625 [DEBUG] ftmod_analog.c:999 [s1c1][1:3]
Received event [ONHOOK] in state [UP]
2013-06-27 16:44:23.936625 [DEBUG] ftmod_analog.c:1052 [s1c1][1:3]
Changed state from UP to DOWN
2013-06-27 16:44:23.946747 [DEBUG] ftmod_analog.c:638 [s1c1][1:3]
Completed state change from UP to DOWN in 6 ms
2013-06-27 16:44:23.946747 [DEBUG] ftmod_analog.c:644 [s1c1][1:3]
Executing state handler on 1:1 for DOWN
2013-06-27 16:44:23.946747 [DEBUG] mod_freetdm.c:2322 got FXS sig [STOP]
2013-06-27 16:44:23.946747 [NOTICE] mod_freetdm.c:2414 Hangup
FreeTDM/1:1/0061312000 [CS_EXECUTE] [NORMAL_CLEARING]
2013-06-27 16:44:23.946747 [DEBUG] switch_channel.c:3135 Send signal
FreeTDM/1:1/0061312000 [KILL]
2013-06-27 16:44:23.946747 [DEBUG] switch_core_session.c:1341 Send
signal FreeTDM/1:1/0061312000 [BREAK]
2013-06-27 16:44:23.946747 [DEBUG] ftdm_io.c:3180 [s1c1][1:3] DTMF debug
is already disabled
2013-06-27 16:44:23.946747 [DEBUG] ftdm_io.c:3212 [s1c1][1:3] No need to
disable input dump
2013-06-27 16:44:23.946747 [DEBUG] ftdm_io.c:3243 [s1c1][1:3] No need to
disable output dump
2013-06-27 16:44:23.946747 [DEBUG] ftdm_io.c:6798 Cleared call with id 12
2013-06-27 16:44:23.946747 [DEBUG] ftdm_io.c:2985 [s1c1][1:3] channel done
2013-06-27 16:44:23.946747 [DEBUG] ftmod_analog.c:972 [s1c1][1:3] ANALOG
CHANNEL 1:1 thread ended.
2013-06-27 16:44:23.946747 [WARNING] ftdm_io.c:4286 [s1c1][1:3] cannot
read from channel that is not open
2013-06-27 16:44:23.946747 [WARNING] mod_freetdm.c:792 Failed to read
from channel FreeTDM/1:1/0061312000 device 1:1!
2013-06-27 16:44:23.946747 [DEBUG] switch_ivr_bridge.c:642 BRIDGE THREAD
DONE [FreeTDM/1:1/0061312000]
2013-06-27 16:44:23.946747 [DEBUG] switch_ivr_bridge.c:667 Send signal
sofia/external/061312000 [BREAK]
2013-06-27 16:44:23.956746 [DEBUG] switch_ivr_bridge.c:642 BRIDGE THREAD
DONE [sofia/external/061312000]
2013-06-27 16:44:23.956746 [DEBUG] switch_ivr_bridge.c:667 Send signal
FreeTDM/1:1/0061312000 [BREAK]
2013-06-27 16:44:23.956746 [NOTICE] switch_ivr_bridge.c:730 Hangup
sofia/external/061312000 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
2013-06-27 16:44:23.956746 [DEBUG] switch_channel.c:3135 Send signal
sofia/external/061312000 [KILL]
2013-06-27 16:44:23.956746 [DEBUG] switch_core_session.c:1341 Send
signal sofia/external/061312000 [BREAK]
2013-06-27 16:44:23.956746 [DEBUG] switch_core_state_machine.c:481
(sofia/external/061312000) State EXCHANGE_MEDIA going to sleep
2013-06-27 16:44:23.956746 [DEBUG] switch_core_state_machine.c:416
(sofia/external/061312000) Running State Change CS_HANGUP
2013-06-27 16:44:23.956746 [DEBUG] switch_core_state_machine.c:679
(sofia/external/061312000) State HANGUP
2013-06-27 16:44:23.956746 [DEBUG] mod_sofia.c:463 Channel
sofia/external/061312000 hanging up, cause: NORMAL_CLEARING
2013-06-27 16:44:23.956746 [DEBUG] mod_sofia.c:515 Sending BYE to
sofia/external/061312000
2013-06-27 16:44:23.956746 [DEBUG] switch_core_state_machine.c:48
sofia/external/061312000 Standard HANGUP, cause: NORMAL_CLEARING
2013-06-27 16:44:23.956746 [DEBUG] switch_core_state_machine.c:679
(sofia/external/061312000) State HANGUP going to sleep
2013-06-27 16:44:23.956746 [DEBUG] switch_core_state_machine.c:692
(sofia/external/061312000) Callstate Change HELD -> HANGUP
2013-06-27 16:44:23.956746 [DEBUG] switch_core_state_machine.c:447
(sofia/external/061312000) State Change CS_HANGUP -> CS_REPORTING
2013-06-27 16:44:23.956746 [DEBUG] switch_core_session.c:1341 Send
signal sofia/external/061312000 [BREAK]
2013-06-27 16:44:23.956746 [DEBUG] switch_core_state_machine.c:416
(sofia/external/061312000) Running State Change CS_REPORTING
2013-06-27 16:44:23.956746 [DEBUG] switch_core_state_machine.c:764
(sofia/external/061312000) State REPORTING
2013-06-27 16:44:23.956746 [DEBUG] switch_core_state_machine.c:92
sofia/external/061312000 Standard REPORTING, cause: NORMAL_CLEARING
2013-06-27 16:44:23.956746 [DEBUG] switch_core_state_machine.c:764
(sofia/external/061312000) State REPORTING going to sleep
2013-06-27 16:44:23.956746 [DEBUG] switch_core_state_machine.c:441
(sofia/external/061312000) State Change CS_REPORTING -> CS_DESTROY
2013-06-27 16:44:23.956746 [DEBUG] switch_core_session.c:1341 Send
signal sofia/external/061312000 [BREAK]
2013-06-27 16:44:23.956746 [DEBUG] switch_core_session.c:1549 Session 24
(sofia/external/061312000) Locked, Waiting on external entities
2013-06-27 16:44:23.956746 [DEBUG] switch_ivr_bridge.c:1531
FreeTDM/1:1/0061312000 skip receive message [UNBRIDGE] (channel is
hungup already)
2013-06-27 16:44:23.956746 [NOTICE] switch_core_session.c:1567 Session
24 (sofia/external/061312000) Ended
2013-06-27 16:44:23.956746 [NOTICE] switch_core_session.c:1571 Close
Channel sofia/external/061312000 [CS_DESTROY]
2013-06-27 16:44:23.956746 [DEBUG] switch_core_session.c:2739
FreeTDM/1:1/0061312000 skip receive message [APPLICATION_EXEC_COMPLETE]
(channel is hungup already)
2013-06-27 16:44:23.956746 [DEBUG] switch_core_state_machine.c:478
(FreeTDM/1:1/0061312000) State EXECUTE going to sleep
2013-06-27 16:44:23.956746 [DEBUG] switch_core_state_machine.c:416
(FreeTDM/1:1/0061312000) Running State Change CS_HANGUP
2013-06-27 16:44:23.956746 [DEBUG] switch_core_state_machine.c:679
(FreeTDM/1:1/0061312000) State HANGUP
2013-06-27 16:44:23.956746 [DEBUG] mod_freetdm.c:544 [1:1]
FreeTDM/1:1/0061312000 CHANNEL HANGUP ENTER
2013-06-27 16:44:23.956746 [DEBUG] mod_freetdm.c:569 Device [1:1] is no
longer attached to FreeTDM/1:1/0061312000. Nothing to do.
2013-06-27 16:44:23.956746 [DEBUG] mod_freetdm.c:640 [1:1]
FreeTDM/1:1/0061312000 CHANNEL HANGUP EXIT
2013-06-27 16:44:23.956746 [DEBUG] switch_core_state_machine.c:48
FreeTDM/1:1/0061312000 Standard HANGUP, cause: NORMAL_CLEARING
2013-06-27 16:44:23.956746 [DEBUG] switch_core_state_machine.c:679
(FreeTDM/1:1/0061312000) State HANGUP going to sleep
2013-06-27 16:44:23.956746 [DEBUG] switch_core_state_machine.c:692
(FreeTDM/1:1/0061312000) Callstate Change ACTIVE -> HANGUP
2013-06-27 16:44:23.956746 [DEBUG] switch_core_state_machine.c:566
(sofia/external/061312000) Callstate Change HANGUP -> DOWN
2013-06-27 16:44:23.956746 [DEBUG] switch_core_state_machine.c:447
(FreeTDM/1:1/0061312000) State Change CS_HANGUP -> CS_REPORTING
2013-06-27 16:44:23.956746 [DEBUG] switch_core_session.c:1341 Send
signal FreeTDM/1:1/0061312000 [BREAK]
2013-06-27 16:44:23.956746 [DEBUG] switch_core_state_machine.c:416
(FreeTDM/1:1/0061312000) Running State Change CS_REPORTING
2013-06-27 16:44:23.956746 [DEBUG] switch_core_state_machine.c:764
(FreeTDM/1:1/0061312000) State REPORTING
2013-06-27 16:44:23.956746 [DEBUG] switch_core_state_machine.c:569
(sofia/external/061312000) Running State Change CS_DESTROY
2013-06-27 16:44:23.956746 [DEBUG] switch_core_state_machine.c:579
(sofia/external/061312000) State DESTROY
2013-06-27 16:44:23.956746 [DEBUG] mod_sofia.c:373
sofia/external/061312000 SOFIA DESTROY
2013-06-27 16:44:23.956746 [DEBUG] switch_core_state_machine.c:99
sofia/external/061312000 Standard DESTROY
2013-06-27 16:44:23.956746 [DEBUG] switch_core_state_machine.c:579
(sofia/external/061312000) State DESTROY going to sleep
2013-06-27 16:44:23.956746 [DEBUG] switch_core_state_machine.c:92
FreeTDM/1:1/0061312000 Standard REPORTING, cause: NORMAL_CLEARING
2013-06-27 16:44:23.956746 [DEBUG] switch_core_state_machine.c:764
(FreeTDM/1:1/0061312000) State REPORTING going to sleep
2013-06-27 16:44:23.956746 [DEBUG] switch_core_state_machine.c:441
(FreeTDM/1:1/0061312000) State Change CS_REPORTING -> CS_DESTROY
2013-06-27 16:44:23.956746 [DEBUG] switch_core_session.c:1341 Send
signal FreeTDM/1:1/0061312000 [BREAK]
2013-06-27 16:44:23.956746 [DEBUG] switch_core_session.c:1549 Session 23
(FreeTDM/1:1/0061312000) Locked, Waiting on external entities
2013-06-27 16:44:23.956746 [NOTICE] switch_core_session.c:1567 Session
23 (FreeTDM/1:1/0061312000) Ended
2013-06-27 16:44:23.956746 [NOTICE] switch_core_session.c:1571 Close
Channel FreeTDM/1:1/0061312000 [CS_DESTROY]
2013-06-27 16:44:23.956746 [DEBUG] switch_core_state_machine.c:566
(FreeTDM/1:1/0061312000) Callstate Change HANGUP -> DOWN
2013-06-27 16:44:23.956746 [DEBUG] switch_core_state_machine.c:569
(FreeTDM/1:1/0061312000) Running State Change CS_DESTROY
2013-06-27 16:44:23.956746 [DEBUG] switch_core_state_machine.c:579
(FreeTDM/1:1/0061312000) State DESTROY
2013-06-27 16:44:23.956746 [DEBUG] switch_core_state_machine.c:99
FreeTDM/1:1/0061312000 Standard DESTROY
2013-06-27 16:44:23.956746 [DEBUG] switch_core_state_machine.c:579
(FreeTDM/1:1/0061312000) State DESTROY going to sleep
freeswitch at internal>

Join us at ClueCon 2011 Aug 9-11, 2011
More information about the FreeSWITCH-users mailing list