[Freeswitch-users] MOH problems

Brian West brian at freeswitch.org
Mon Nov 17 06:32:51 PST 2008 

Please describe the exact scenario that fails to work!  Include  
example case if possible.

/b

Sent from my iPhone

On Nov 17, 2008, at 7:33 AM, Helmut Kuper <helmut.kuper at ewetel.de>  
wrote:

> -----BEGIN PGP SIGNED MESSAGE-----
> Hash: SHA1
>
> Hello,
>
> I did a completed reinstall now. svn latest trunk checkout, bootstrap,
> configure, make install
>
>
> I did the same with revision 10138 on same server.
>
> For both I used same config. MOH worked on 10138, but not on 10423.
>
>
> regards
> helmut
>
> Helmut Kuper schrieb:
>> Hello,
>>
>>
>> yes I have: FreeSWITCH Version 1.0.trunk (10423M)
>>
>> same problem. No MOH
>>
>>
>> Console log:
>>
>>
>>
>> 2008-11-17 13:12:20 [DEBUG] sofia_glue.c:2382  
>> sofia_glue_negotiate_sdp()
>> Audio Codec Compare [pcmu:0:8000]/[G722:9:8000]
>> 2008-11-17 13:12:20 [DEBUG] sofia_glue.c:2382  
>> sofia_glue_negotiate_sdp()
>> Audio Codec Compare [pcmu:0:8000]/[PCMU:0:8000]
>> 2008-11-17 13:12:20 [DEBUG] sofia_glue.c:1590
>> sofia_glue_tech_set_codec() Set Codec sofia/internal/1000 at 85.16.246.6
>> PCMU/8000 20 ms 160 samples
>> 2008-11-17 13:12:20 [DEBUG] sofia_glue.c:2346  
>> sofia_glue_negotiate_sdp()
>> Set 2833 dtmf payload to 101
>> 2008-11-17 13:12:20 [DEBUG] sofia.c:2459 sofia_handle_sip_i_state()
>> (sofia/internal/1000 at 85.16.246.6) State Change CS_NEW -> CS_INIT
>> 2008-11-17 13:12:20 [DEBUG] switch_core_session.c:779
>> switch_core_session_signal_state_change() Send signal
>> sofia/internal/1000 at 85.16.246.6 [BREAK]
>> 2008-11-17 13:12:20 [DEBUG] switch_core_state_machine.c:375
>> switch_core_session_run() (sofia/internal/1000 at 85.16.246.6) Running
>> State Change CS_INIT
>> 2008-11-17 13:12:20 [DEBUG] switch_core_state_machine.c:423
>> switch_core_session_run() (sofia/internal/1000 at 85.16.246.6) State  
>> INIT
>> 2008-11-17 13:12:20 [DEBUG] mod_sofia.c:83 sofia_on_init()
>> sofia/internal/1000 at 85.16.246.6 SOFIA INIT
>> 2008-11-17 13:12:20 [DEBUG] mod_sofia.c:111 sofia_on_init()
>> (sofia/internal/1000 at 85.16.246.6) State Change CS_INIT -> CS_ROUTING
>> 2008-11-17 13:12:20 [DEBUG] switch_core_session.c:779
>> switch_core_session_signal_state_change() Send signal
>> sofia/internal/1000 at 85.16.246.6 [BREAK]
>> 2008-11-17 13:12:20 [DEBUG] switch_core_state_machine.c:423
>> switch_core_session_run() (sofia/internal/1000 at 85.16.246.6) State  
>> INIT
>> going to sleep
>> 2008-11-17 13:12:20 [DEBUG] switch_core_state_machine.c:375
>> switch_core_session_run() (sofia/internal/1000 at 85.16.246.6) Running
>> State Change CS_ROUTING
>> 2008-11-17 13:12:20 [DEBUG] switch_core_state_machine.c:426
>> switch_core_session_run() (sofia/internal/1000 at 85.16.246.6) State  
>> ROUTING
>> 2008-11-17 13:12:20 [DEBUG] mod_sofia.c:130 sofia_on_routing()
>> sofia/internal/1000 at 85.16.246.6 SOFIA ROUTING
>> 2008-11-17 13:12:20 [DEBUG] switch_core_state_machine.c:64
>> switch_core_standard_on_routing() sofia/internal/1000 at 85.16.246.6
>> Standard ROUTING
>> 2008-11-17 13:12:20 [INFO] mod_dialplan_xml.c:233 dialplan_hunt()
>> Processing Benjamin FreeSWITCH->9000 in context default
>> 2008-11-17 13:12:20 [DEBUG] mod_dialplan_xml.c:117 parse_exten()  
>> Regex:
>> [Local_Extension] destination_number(9000) =~
>> /^(10[01][0-9]|28[0-9][0-9]|27[0-9][0-9])$/
>> 2008-11-17 13:12:20 [DEBUG] mod_dialplan_xml.c:119 parse_exten()  
>> Regex
>> mismatch
>> 2008-11-17 13:12:20 [DEBUG] mod_dialplan_xml.c:117 parse_exten()  
>> Regex:
>> [vmain] destination_number(9000) =~ /^9999$/
>> 2008-11-17 13:12:20 [DEBUG] mod_dialplan_xml.c:119 parse_exten()  
>> Regex
>> mismatch
>> 2008-11-17 13:12:20 [DEBUG] mod_dialplan_xml.c:117 parse_exten()  
>> Regex:
>> [tts1] destination_number(9000) =~ /^4200$/
>> 2008-11-17 13:12:20 [DEBUG] mod_dialplan_xml.c:119 parse_exten()  
>> Regex
>> mismatch
>> 2008-11-17 13:12:20 [DEBUG] mod_dialplan_xml.c:117 parse_exten()  
>> Regex:
>> [playback] destination_number(9000) =~ /^5000$/
>> 2008-11-17 13:12:20 [DEBUG] mod_dialplan_xml.c:119 parse_exten()  
>> Regex
>> mismatch
>> 2008-11-17 13:12:20 [DEBUG] mod_dialplan_xml.c:117 parse_exten()  
>> Regex:
>> [parallel call] destination_number(9000) =~ /^(6000)$/
>> 2008-11-17 13:12:20 [DEBUG] mod_dialplan_xml.c:119 parse_exten()  
>> Regex
>> mismatch
>> 2008-11-17 13:12:20 [DEBUG] mod_dialplan_xml.c:117 parse_exten()  
>> Regex:
>> [hold_music] destination_number(9000) =~ /^9000$/
>> 2008-11-17 13:12:20 [DEBUG] switch_core_state_machine.c:100
>> switch_core_standard_on_routing() (sofia/internal/1000 at 85.16.246.6)
>> State Change CS_ROUTING -> CS_EXECUTE
>> 2008-11-17 13:12:20 [DEBUG] switch_core_session.c:779
>> switch_core_session_signal_state_change() Send signal
>> sofia/internal/1000 at 85.16.246.6 [BREAK]
>> 2008-11-17 13:12:20 [DEBUG] switch_core_state_machine.c:426
>> switch_core_session_run() (sofia/internal/1000 at 85.16.246.6) State
>> ROUTING going to sleep
>> 2008-11-17 13:12:20 [DEBUG] switch_core_state_machine.c:375
>> switch_core_session_run() (sofia/internal/1000 at 85.16.246.6) Running
>> State Change CS_EXECUTE
>> 2008-11-17 13:12:20 [DEBUG] switch_core_state_machine.c:433
>> switch_core_session_run() (sofia/internal/1000 at 85.16.246.6) State  
>> EXECUTE
>> 2008-11-17 13:12:20 [DEBUG] mod_sofia.c:173 sofia_on_execute()
>> sofia/internal/1000 at 85.16.246.6 SOFIA EXECUTE
>> 2008-11-17 13:12:20 [DEBUG] switch_core_state_machine.c:137
>> switch_core_standard_on_execute() sofia/internal/1000 at 85.16.246.6
>> Standard EXECUTE
>> 2008-11-17 13:12:20 [DEBUG] switch_core_state_machine.c:152
>> switch_core_standard_on_execute() sofia/internal/1000 at 85.16.246.6
>> Execute answer()
>> 2008-11-17 13:12:20 [DEBUG] switch_channel.c:1712
>> switch_channel_perform_answer() sofia/internal/1000 at 85.16.246.6  
>> receive
>> message [SWITCH_MESSAGE_INDICATE_ANSWER]
>> 2008-11-17 13:12:20 [DEBUG] sofia_glue.c:1808  
>> sofia_glue_activate_rtp()
>> AUDIO RTP [sofia/internal/1000 at 85.16.246.6] 85.16.246.6 port 23410 ->
>> 85.16.245.208 port 63444 codec: 0 ms: 20
>> 2008-11-17 13:12:20 [DEBUG] switch_rtp.c:849 switch_rtp_create()
>> Starting timer [soft] 160 bytes per 20000ms
>> 2008-11-17 13:12:20 [DEBUG] mod_sofia.c:497 sofia_answer_channel()  
>> Local
>> SDP sofia/internal/1000 at 85.16.246.6:
>> v=0
>> o=FreeSWITCH 1226900530 1226900531 IN IP4 85.16.246.6
>> s=FreeSWITCH
>> c=IN IP4 85.16.246.6
>> t=0 0
>> m=audio 23410 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
>>
>> 2008-11-17 13:12:20 [DEBUG] switch_core_session.c:489
>> switch_core_session_perform_receive_message() Send signal
>> sofia/internal/1000 at 85.16.246.6 [BREAK]
>> 2008-11-17 13:12:20 [NOTICE] mod_dptools.c:610 answer_function()  
>> Channel
>> [sofia/internal/1000 at 85.16.246.6] has been answered
>> 2008-11-17 13:12:20 [DEBUG] switch_core_state_machine.c:152
>> switch_core_standard_on_execute() sofia/internal/1000 at 85.16.246.6
>> Execute playback(local_stream://moh)
>> 2008-11-17 13:12:20 [DEBUG] mod_local_stream.c:327
>> local_stream_file_open() Opening Stream [moh/8000] 8000hz
>> 2008-11-17 13:12:20 [DEBUG] switch_ivr_play_say.c:932
>> switch_ivr_play_file() Codec Activated L16 at 8000hz 1 channels 20ms
>> 2008-11-17 13:12:20 [DEBUG] switch_core_io.c:610
>> switch_core_session_write_frame() sofia/internal/1000 at 85.16.246.6
>> receive message [SWITCH_MESSAGE_INDICATE_TRANSCODING_NECESSARY]
>> 2008-11-17 13:12:20 [DEBUG] switch_core_session.c:489
>> switch_core_session_perform_receive_message() Send signal
>> sofia/internal/1000 at 85.16.246.6 [BREAK]
>> 2008-11-17 13:12:20 [DEBUG] sofia.c:2314 sofia_handle_sip_i_state()
>> Channel sofia/internal/1000 at 85.16.246.6 entering state [completed]
>> 2008-11-17 13:12:20 [DEBUG] sofia.c:2314 sofia_handle_sip_i_state()
>> Channel sofia/internal/1000 at 85.16.246.6 entering state [ready]
>> 2008-11-17 13:12:24 [DEBUG] sofia.c:2314 sofia_handle_sip_i_state()
>> Channel sofia/internal/1000 at 85.16.246.6 entering state [terminated]
>> 2008-11-17 13:12:24 [NOTICE] sofia.c:2853 sofia_handle_sip_i_state()
>> Hangup sofia/internal/1000 at 85.16.246.6 [CS_EXECUTE] [NORMAL_CLEARING]
>> 2008-11-17 13:12:24 [DEBUG] switch_channel.c:1441
>> switch_channel_perform_hangup() Send signal
>> sofia/internal/1000 at 85.16.246.6 [KILL]
>> 2008-11-17 13:12:24 [DEBUG] switch_core_session.c:779
>> switch_core_session_signal_state_change() Send signal
>> sofia/internal/1000 at 85.16.246.6 [BREAK]
>> 2008-11-17 13:12:24 [DEBUG] switch_ivr_play_say.c:1222
>> switch_ivr_play_file() done playing file
>> 2008-11-17 13:12:24 [DEBUG] switch_core_state_machine.c:433
>> switch_core_session_run() (sofia/internal/1000 at 85.16.246.6) State
>> EXECUTE going to sleep
>> 2008-11-17 13:12:24 [DEBUG] switch_core_state_machine.c:375
>> switch_core_session_run() (sofia/internal/1000 at 85.16.246.6) Running
>> State Change CS_HANGUP
>> 2008-11-17 13:12:24 [DEBUG] switch_core_state_machine.c:403
>> switch_core_session_run() (sofia/internal/1000 at 85.16.246.6) State  
>> HANGUP
>> 2008-11-17 13:12:24 [DEBUG] mod_sofia.c:287 sofia_on_hangup() Channel
>> sofia/internal/1000 at 85.16.246.6 hanging up, cause: NORMAL_CLEARING
>> 2008-11-17 13:12:24 [DEBUG] switch_core_state_machine.c:46
>> switch_core_standard_on_hangup() sofia/internal/1000 at 85.16.246.6
>> Standard HANGUP, cause: NORMAL_CLEARING
>> 2008-11-17 13:12:24 [DEBUG] switch_core_state_machine.c:403
>> switch_core_session_run() (sofia/internal/1000 at 85.16.246.6) State  
>> HANGUP
>> going to sleep
>> 2008-11-17 13:12:24 [DEBUG] switch_core_session.c:911
>> switch_core_session_thread() Session 5 (sofia/internal/1000 at 85.16.246.6 
>> )
>> Locked, Waiting on external entities
>> 2008-11-17 13:12:24 [NOTICE] switch_core_session.c:929
>> switch_core_session_thread() Session 5 (sofia/internal/1000 at 85.16.246.6 
>> )
>> Ended
>> 2008-11-17 13:12:24 [NOTICE] switch_core_session.c:931
>> switch_core_session_thread() Close Channel
>> sofia/internal/1000 at 85.16.246.6 [CS_HANGUP]
>>
>>
>>
>> regards
>> helmut
>>
>> Brian West schrieb:
>>> Have you updated to the latest SVN trunk?
>>
>>> /b
>>
>>> On Nov 17, 2008, at 2:05 AM, Helmut Kuper wrote:
>>
>>>> -----BEGIN PGP SIGNED MESSAGE-----
>>>> Hash: SHA1
>>>>
>>>> Hello,
>>>>
>>>> just to get an answer ..
>>>>
>>>> regards
>>>> Helmut
>>
>>> _______________________________________________
>>> Freeswitch-users mailing list
>>> Freeswitch-users at lists.freeswitch.org
>>> http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
>>> UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
>>> http://www.freeswitch.org
>>
>>
>
> _______________________________________________
> Freeswitch-users mailing list
> Freeswitch-users at lists.freeswitch.org
> http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
> UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
> http://www.freeswitch.org
>
>
> -----BEGIN PGP SIGNATURE-----
> Version: GnuPG v1.4.9 (MingW32)
>
> iEYEARECAAYFAkkhcrUACgkQ4tZeNddg3dwSswCfby8wvlF3s/fcQQ+glNqHvs5j
> 7HcAoKWk9wI1pnRHPsl8v16u5uCcbTGC
> =E7+d
> -----END PGP SIGNATURE-----
>
> _______________________________________________
> Freeswitch-users mailing list
> Freeswitch-users at lists.freeswitch.org
> http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
> UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
> http://www.freeswitch.org

More information about the FreeSWITCH-users mailing list