[Freeswitch-users] Both phone rang, but no voice

Thu Aug 21 00:26:23 PDT 2008

None of the phones are on the same lan as freeswitch, right?
If so, one of the phones do not use stun, but reports it's local ip to FS.

2008-08-21 15:15:58 [DEBUG] sofia_glue.c:1756
sofia_glue_activate_rtp() AUDIO RTP [sofia/external/01160122263828]
192.168.253.101 port 23678 -> 130.94.88.93 port 10116 codec: 0 ms: 20

On Thu, Aug 21, 2008 at 9:16 AM, Adeel Ansari <adeel.gnome at gmail.com> wrote:
> Below is the log in debug mode.
>
>
> ==========
> freeswitch at gnome> originate sofia/gateway/gizmo1/01160176905074
> &bridge(sofia/gateway/gizmo2/01160122263828)
> 2008-08-21 15:15:54 [NOTICE] switch_channel.c:535 switch_channel_set_name()
> New Channel sofia/external/01160176905074
> [fdf9aab4-6f50-11dd-8cbb-f7afdd15cc69]
> 2008-08-21 15:15:54 [DEBUG] mod_sofia.c:2020 sofia_outgoing_channel()
> sofia/external/01160176905074 State Change CS_NEW -> CS_INIT
> 2008-08-21 15:15:54 [DEBUG] switch_core_session.c:727
> switch_core_session_signal_state_change() Kill sofia/external/01160176905074
> [BREAK]
> 2008-08-21 15:15:54 [DEBUG] switch_core_state_machine.c:365
> switch_core_session_run() sofia/external/01160176905074 Running State Change
> CS_INIT
> 2008-08-21 15:15:54 [DEBUG] switch_core_state_machine.c:415
> switch_core_session_run() (sofia/external/01160176905074) State INIT
> 2008-08-21 15:15:54 [DEBUG] mod_sofia.c:80 sofia_on_init()
> sofia/external/01160176905074 SOFIA INIT
> 2008-08-21 15:15:54 [DEBUG] mod_sofia.c:107 sofia_on_init()
> sofia/external/01160176905074 State Change CS_INIT -> CS_ROUTING
> 2008-08-21 15:15:54 [DEBUG] switch_core_session.c:727
> switch_core_session_signal_state_change() Kill sofia/external/01160176905074
> [BREAK]
> 2008-08-21 15:15:54 [DEBUG] switch_core_state_machine.c:415
> switch_core_session_run() (sofia/external/01160176905074) State INIT going
> to sleep
> 2008-08-21 15:15:54 [DEBUG] switch_core_state_machine.c:365
> switch_core_session_run() sofia/external/01160176905074 Running State Change
> CS_ROUTING
> 2008-08-21 15:15:54 [DEBUG] switch_core_state_machine.c:420
> switch_core_session_run() (sofia/external/01160176905074) State ROUTING
> 2008-08-21 15:15:54 [DEBUG] mod_sofia.c:119 sofia_on_routing()
> sofia/external/01160176905074 SOFIA ROUTING
> 2008-08-21 15:15:54 [DEBUG] switch_ivr_originate.c:57 originate_on_routing()
> sofia/external/01160176905074 State Change CS_ROUTING -> CS_CONSUME_MEDIA
> 2008-08-21 15:15:54 [DEBUG] switch_core_session.c:727
> switch_core_session_signal_state_change() Kill sofia/external/01160176905074
> [BREAK]
> 2008-08-21 15:15:54 [DEBUG] switch_core_state_machine.c:420
> switch_core_session_run() (sofia/external/01160176905074) State ROUTING
> going to sleep
> 2008-08-21 15:15:54 [DEBUG] switch_core_state_machine.c:365
> switch_core_session_run() sofia/external/01160176905074 Running State Change
> CS_CONSUME_MEDIA
> 2008-08-21 15:15:54 [DEBUG] switch_core_state_machine.c:442
> switch_core_session_run() (sofia/external/01160176905074) State
> CONSUME_MEDIA
> 2008-08-21 15:15:54 [DEBUG] sofia.c:200 sofia_event_callback() event
> [nua_i_state] status [0][INVITE sent] session: sofia/external/01160176905074
> 2008-08-21 15:15:54 [DEBUG] sofia.c:2145 sofia_handle_sip_i_state() Channel
> sofia/external/01160176905074 entering state [calling]
> 2008-08-21 15:15:54 [DEBUG] sofia.c:200 sofia_event_callback() event
> [nua_r_invite] status [401][Unauthorized] session:
> sofia/external/01160176905074
> 2008-08-21 15:15:54 [DEBUG] sofia_reg.c:1098
> sofia_reg_handle_sip_r_challenge() Authenticating 'FreeSWITCH' with
> 'Digest:"proxy01.sipphone.com":dasbit102:102'.
> 2008-08-21 15:15:54 [DEBUG] sofia.c:200 sofia_event_callback() event
> [nua_i_state] status [0][INVITE sent] session: sofia/external/01160176905074
> 2008-08-21 15:15:54 [DEBUG] sofia.c:2145 sofia_handle_sip_i_state() Channel
> sofia/external/01160176905074 entering state [calling]
> 2008-08-21 15:15:56 [DEBUG] sofia.c:2145 sofia_handle_sip_i_state() Channel
> sofia/external/01160176905074 entering state [ready]
> 2008-08-21 15:15:56 [DEBUG] sofia.c:2149 sofia_handle_sip_i_state() Remote
> SDP:
> v=0
> o=root 7709 7709 IN IP4 130.94.88.90
> s=session
> c=IN IP4 130.94.88.90
> t=0 0
> m=audio 19672 RTP/AVP 0 8 101
> a=rtpmap:0 PCMU/8000
> a=rtpmap:8 PCMA/8000
> a=rtpmap:101 telephone-event/8000
> a=fmtp:101 0-16
> a=silenceSupp:off - - - -
>
> 2008-08-21 15:15:56 [DEBUG] sofia_glue.c:2297 sofia_glue_negotiate_sdp()
> Audio Codec Compare [PCMU:0:8000]/[PCMU:0:8000]
> 2008-08-21 15:15:56 [DEBUG] sofia_glue.c:1547 sofia_glue_tech_set_codec()
> Set Codec sofia/external/01160176905074 PCMU/8000 20 ms 160 samples
> 2008-08-21 15:15:56 [DEBUG] sofia_glue.c:2260 sofia_glue_negotiate_sdp() Set
> 2833 dtmf payload to 101
> 2008-08-21 15:15:56 [DEBUG] sofia_glue.c:1756 sofia_glue_activate_rtp()
> AUDIO RTP [sofia/external/01160176905074] 192.168.253.101 port 31444 ->
> 130.94.88.90 port 19672 codec: 0 ms: 20
> 2008-08-21 15:15:56 [DEBUG] switch_rtp.c:813 switch_rtp_create() Starting
> timer [soft] 160 bytes per 20000ms
> 2008-08-21 15:15:56 [DEBUG] switch_ivr_originate.c:1370
> switch_ivr_originate() Originate Resulted in Success:
> [sofia/external/01160176905074]
> 2008-08-21 15:15:56 [DEBUG] mod_commands.c:1612 originate_function()
> sofia/external/01160176905074 State Change CS_CONSUME_MEDIA -> CS_EXECUTE
> 2008-08-21 15:15:56 [DEBUG] switch_core_session.c:727
> switch_core_session_signal_state_change() Kill sofia/external/01160176905074
> [BREAK]
> 2008-08-21 15:15:56 [NOTICE] sofia.c:2507 sofia_handle_sip_i_state() Channel
> [sofia/external/01160176905074] has been answered
> API CALL [originate(sofia/gateway/gizmo1/01160176905074
> &bridge(sofia/gateway/gizmo2/01160122263828))] output:
> +OK fdf9aab4-6f50-11dd-8cbb-f7afdd15cc69
>
> freeswitch at gnome> 2008-08-21 15:15:56 [DEBUG]
> switch_core_state_machine.c:442 switch_core_session_run()
> (sofia/external/01160176905074) State CONSUME_MEDIA going to sleep
> 2008-08-21 15:15:56 [DEBUG] switch_core_state_machine.c:365
> switch_core_session_run() sofia/external/01160176905074 Running State Change
> CS_EXECUTE
> 2008-08-21 15:15:56 [DEBUG] switch_core_state_machine.c:430
> switch_core_session_run() (sofia/external/01160176905074) State EXECUTE
> 2008-08-21 15:15:56 [DEBUG] mod_sofia.c:156 sofia_on_execute()
> sofia/external/01160176905074 SOFIA EXECUTE
> 2008-08-21 15:15:56 [DEBUG] switch_core_state_machine.c:129
> switch_core_standard_on_execute() Standard EXECUTE
> 2008-08-21 15:15:56 [DEBUG] switch_core_state_machine.c:140
> switch_core_standard_on_execute() sofia/external/01160176905074 Execute
> bridge(sofia/gateway/gizmo2/01160122263828)
> 2008-08-21 15:15:56 [NOTICE] switch_channel.c:535 switch_channel_set_name()
> New Channel sofia/external/01160122263828
> [ff8093e8-6f50-11dd-8cbb-f7afdd15cc69]
> 2008-08-21 15:15:56 [DEBUG] mod_sofia.c:2020 sofia_outgoing_channel()
> sofia/external/01160122263828 State Change CS_NEW -> CS_INIT
> 2008-08-21 15:15:56 [DEBUG] switch_core_session.c:727
> switch_core_session_signal_state_change() Kill sofia/external/01160122263828
> [BREAK]
> 2008-08-21 15:15:56 [DEBUG] switch_core_state_machine.c:365
> switch_core_session_run() sofia/external/01160122263828 Running State Change
> CS_INIT
> 2008-08-21 15:15:56 [DEBUG] switch_core_state_machine.c:415
> switch_core_session_run() (sofia/external/01160122263828) State INIT
> 2008-08-21 15:15:56 [DEBUG] mod_sofia.c:80 sofia_on_init()
> sofia/external/01160122263828 SOFIA INIT
> 2008-08-21 15:15:56 [DEBUG] mod_sofia.c:107 sofia_on_init()
> sofia/external/01160122263828 State Change CS_INIT -> CS_ROUTING
> 2008-08-21 15:15:56 [DEBUG] switch_core_session.c:727
> switch_core_session_signal_state_change() Kill sofia/external/01160122263828
> [BREAK]
> 2008-08-21 15:15:56 [DEBUG] switch_core_state_machine.c:415
> switch_core_session_run() (sofia/external/01160122263828) State INIT going
> to sleep
> 2008-08-21 15:15:56 [DEBUG] switch_core_state_machine.c:365
> switch_core_session_run() sofia/external/01160122263828 Running State Change
> CS_ROUTING
> 2008-08-21 15:15:56 [DEBUG] switch_core_state_machine.c:420
> switch_core_session_run() (sofia/external/01160122263828) State ROUTING
> 2008-08-21 15:15:56 [DEBUG] mod_sofia.c:119 sofia_on_routing()
> sofia/external/01160122263828 SOFIA ROUTING
> 2008-08-21 15:15:56 [DEBUG] switch_ivr_originate.c:57 originate_on_routing()
> sofia/external/01160122263828 State Change CS_ROUTING -> CS_CONSUME_MEDIA
> 2008-08-21 15:15:56 [DEBUG] switch_core_session.c:727
> switch_core_session_signal_state_change() Kill sofia/external/01160122263828
> [BREAK]
> 2008-08-21 15:15:56 [DEBUG] switch_core_state_machine.c:420
> switch_core_session_run() (sofia/external/01160122263828) State ROUTING
> going to sleep
> 2008-08-21 15:15:56 [DEBUG] switch_core_state_machine.c:365
> switch_core_session_run() sofia/external/01160122263828 Running State Change
> CS_CONSUME_MEDIA
> 2008-08-21 15:15:56 [DEBUG] switch_core_state_machine.c:442
> switch_core_session_run() (sofia/external/01160122263828) State
> CONSUME_MEDIA
> 2008-08-21 15:15:56 [DEBUG] sofia.c:200 sofia_event_callback() event
> [nua_i_state] status [0][INVITE sent] session: sofia/external/01160122263828
> 2008-08-21 15:15:56 [DEBUG] sofia.c:2145 sofia_handle_sip_i_state() Channel
> sofia/external/01160122263828 entering state [calling]
> 2008-08-21 15:15:56 [DEBUG] sofia.c:200 sofia_event_callback() event
> [nua_r_invite] status [401][Unauthorized] session:
> sofia/external/01160122263828
> 2008-08-21 15:15:56 [DEBUG] sofia_reg.c:1098
> sofia_reg_handle_sip_r_challenge() Authenticating 'FreeSWITCH' with
> 'Digest:"proxy01.sipphone.com":dasbit103:103'.
> 2008-08-21 15:15:56 [DEBUG] sofia.c:200 sofia_event_callback() event
> [nua_i_state] status [0][INVITE sent] session: sofia/external/01160122263828
> 2008-08-21 15:15:56 [DEBUG] sofia.c:2145 sofia_handle_sip_i_state() Channel
> sofia/external/01160122263828 entering state [calling]
> 2008-08-21 15:15:58 [DEBUG] sofia.c:2145 sofia_handle_sip_i_state() Channel
> sofia/external/01160122263828 entering state [ready]
> 2008-08-21 15:15:58 [DEBUG] sofia.c:2149 sofia_handle_sip_i_state() Remote
> SDP:
> v=0
> o=root 9902 9902 IN IP4 130.94.88.93
> s=session
> c=IN IP4 130.94.88.93
> t=0 0
> m=audio 10116 RTP/AVP 0 8 101
> a=rtpmap:0 PCMU/8000
> a=rtpmap:8 PCMA/8000
> a=rtpmap:101 telephone-event/8000
> a=fmtp:101 0-16
> a=silenceSupp:off - - - -
>
> 2008-08-21 15:15:58 [DEBUG] sofia_glue.c:2297 sofia_glue_negotiate_sdp()
> Audio Codec Compare [PCMU:0:8000]/[PCMU:0:8000]
> 2008-08-21 15:15:58 [DEBUG] sofia_glue.c:1547 sofia_glue_tech_set_codec()
> Set Codec sofia/external/01160122263828 PCMU/8000 20 ms 160 samples
> 2008-08-21 15:15:58 [DEBUG] sofia_glue.c:2260 sofia_glue_negotiate_sdp() Set
> 2833 dtmf payload to 101
> 2008-08-21 15:15:58 [DEBUG] sofia_glue.c:1756 sofia_glue_activate_rtp()
> AUDIO RTP [sofia/external/01160122263828] 192.168.253.101 port 23678 ->
> 130.94.88.93 port 10116 codec: 0 ms: 20
> 2008-08-21 15:15:58 [DEBUG] switch_rtp.c:813 switch_rtp_create() Starting
> timer [soft] 160 bytes per 20000ms
> 2008-08-21 15:15:58 [DEBUG] switch_channel.c:1541
> switch_channel_perform_mark_answered() Kill sofia/external/01160176905074
> [BREAK]
> 2008-08-21 15:15:58 [NOTICE] sofia.c:2507 sofia_handle_sip_i_state() Channel
> [sofia/external/01160122263828] has been answered
> 2008-08-21 15:15:58 [DEBUG] switch_ivr_originate.c:1370
> switch_ivr_originate() Originate Resulted in Success:
> [sofia/external/01160122263828]
> 2008-08-21 15:15:58 [DEBUG] switch_core_session.c:435
> switch_core_session_receive_message() Kill sofia/external/01160122263828
> [BREAK]
> 2008-08-21 15:15:58 [DEBUG] switch_core_session.c:435
> switch_core_session_receive_message() Kill sofia/external/01160176905074
> [BREAK]
> 2008-08-21 15:15:58 [DEBUG] switch_ivr_bridge.c:778
> switch_ivr_multi_threaded_bridge() sofia/external/01160122263828 State
> Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
> 2008-08-21 15:15:58 [DEBUG] switch_core_session.c:727
> switch_core_session_signal_state_change() Kill sofia/external/01160122263828
> [BREAK]
> 2008-08-21 15:15:58 [DEBUG] switch_core_state_machine.c:442
> switch_core_session_run() (sofia/external/01160122263828) State
> CONSUME_MEDIA going to sleep
> 2008-08-21 15:15:58 [DEBUG] switch_core_state_machine.c:365
> switch_core_session_run() sofia/external/01160122263828 Running State Change
> CS_EXCHANGE_MEDIA
> 2008-08-21 15:15:58 [DEBUG] switch_core_state_machine.c:433
> switch_core_session_run() (sofia/external/01160122263828) State
> EXCHANGE_MEDIA
> 2008-08-21 15:15:58 [DEBUG] mod_sofia.c:365 sofia_on_exchange_media() SOFIA
> LOOPBACK
> ==========
>
> Thanks.
>
> On Thu, Aug 21, 2008 at 3:07 PM, Jonas Gauffin <jonas.gauffin at gmail.com>
> wrote:
>>
>> Show us logs of a call attempt.
>>
>> On Thu, Aug 21, 2008 at 8:42 AM, Adeel Ansari <adeel.gnome at gmail.com>
>> wrote:
>> > 5080 and 5060 both are open. Moreover, other PBX is working fine on
>> > 5060.
>> >
>> > Further, I have tried by setting the parameters sip-ip and rtp-ip to
>> > auto;
>> > ext-sip-ip and ext-sip-ip to my public ip. Nothing is working. Both the
>> > phone rang always, but no voice.
>> >
>> > Anyother suggestions??
>> > Thanks.
>> >
>> > On Thu, Aug 21, 2008 at 1:01 PM, Brian West <brian at freeswitch.org>
>> > wrote:
>> >>
>> >> Chech and see if a firewall is on!  Also are you behind nat?
>> >> /b
>> >>
>> >> Sent from my iPhone
>> >> On Aug 20, 2008, at 11:27 PM, "Adeel Ansari" <adeel.gnome at gmail.com>
>> >> wrote:
>> >>
>> >> Hi all,
>> >>
>> >> I have tried the command below,
>> >>
>> >> ====
>> >> originate sofia/gateway/gizmo1/01160176xxxxxx
>> >> &bridge(sofia/gateway/gizmo2/01160122xxxxxx)
>> >> ====
>> >>
>> >> Its working, phone rang, but no voice. Am I missing something?
>> >>
>> >> Thanks.
>> >>
>> >> --
>> >> Best,
>> >> Adeel Ansari
>> >>
>> >> http://www.linkedin.com/in/adeelansari
>> >>
>> >> _______________________________________________
>> >> 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
>> >>
>> >
>> >
>> >
>> > --
>> > Best,
>> > Adeel Ansari
>> >
>> > http://www.linkedin.com/in/adeelansari
>> >
>> > _______________________________________________
>> > 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
>
>
>
> --
> Best,
> Adeel Ansari
>
> http://www.linkedin.com/in/adeelansari
>
> _______________________________________________
> 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
>
>