[Freeswitch-users] Transfer Problem

Jonathan Barou jbarou at sqli.com
Wed Dec 2 01:54:05 PST 2009 

Any ideas ?

Thanks

2009/11/30 Jonathan Barou <jbarou at sqli.com>

> My version is FreeSWITCH Version 1.0.trunk (15691M)
>
> http://jira.freeswitch.org/browse/FSBUILD-213
>
> Thanks you.
>
> 2009/11/27 Anthony Minessale <anthony.minessale at gmail.com>
>
> by latest do you mean SVN trunk?
>>
>> Can you issue the command "sofia profile internal siptrace on" before
>> capturing your trace and post the results
>> to http://pastebin.freeswitch.org or open a jira
>> http://jira.freeswitch.org on the issue and attach the log after you
>> create the issue ticket, don't include it in the mailing list.
>>
>>
>> On Fri, Nov 27, 2009 at 10:47 AM, Jonathan Barou <jbarou at sqli.com> wrote:
>>
>>> Hi everybody,
>>>
>>> I'm actually using the lastest version of Freeswitch, I have a problem. I
>>> have a trunk SIP with my PABX.
>>>
>>> There is 3 phones : 1. one Alcatel Advanced with number 368 (on PABX)
>>>                              2. one Alcatel IpTouch 4028 with number 987
>>> (on PABX)
>>>                              3.  one Siemens Gigaset A580 IP with number
>>> 8401 (on Freeswitch)
>>>
>>>
>>> *The first test* is to say to the phone 2 to transfer all the call to
>>> number 8401. So when I dial 987 on the phone 1, all work perfectly, the
>>> phone 3 is  ringing and it's work. I have that in the log :
>>>
>>> 2009-11-27 16:52:18.677299 [INFO] switch_ivr_originate.c:1024 Sending
>>> early media
>>>
>>> 2009-11-27 16:52:18.677299 [DEBUG] sofia_glue.c:2375 AUDIO RTP
>>> [sofia/internal/368 at 10.33.69.246] 10.33.169.92 port 23054 ->
>>> 10.33.69.246 port 32000 codec: 8 ms: 90
>>>
>>> 2009-11-27 16:52:18.677299 [DEBUG] switch_rtp.c:1155 Starting timer
>>> [soft] 720 bytes per 90ms
>>>
>>> 2009-11-27 16:52:18.687301 [INFO] mod_sofia.c:1706 Ring SDP:
>>>
>>> v=0
>>>
>>> o=FreeSWITCH 1259314084 1259314085 IN IP4 10.33.169.92
>>>
>>> s=FreeSWITCH
>>>
>>> c=IN IP4 10.33.169.92
>>>
>>> t=0 0
>>>
>>> m=audio 23054 RTP/AVP 8 106
>>>
>>> a=rtpmap:8 PCMA/8000
>>>
>>> a=rtpmap:106 telephone-event/8000
>>>
>>> a=fmtp:106 0-16
>>>
>>> a=silenceSupp:off - - - -
>>>
>>> a=ptime:90
>>>
>>> a=sendrecv
>>>
>>>
>>> 2009-11-27 16:52:18.687301 [NOTICE] mod_sofia.c:1709 Pre-Answer
>>> sofia/internal/368 at 10.33.69.246!
>>>
>>> 2009-11-27 16:52:18.687301 [DEBUG] switch_core_session.c:706 Send signal
>>> sofia/internal/sip:8401 at 10.33.170.231:5060 [BREAK]
>>>
>>> 2009-11-27 16:52:18.687301 [DEBUG] sofia.c:412 sofia/internal/
>>> sip:8401 at 10.33.170.231:5060 receive message [DISPLAY]
>>>
>>> 2009-11-27 16:52:18.687301 [DEBUG] sofia.c:3691 Channel sofia/internal/
>>> 368 at 10.33.69.246 skipping state [early][183]
>>>
>>> 2009-11-27 16:52:18.687301 [DEBUG] switch_core_session.c:645 Send signal
>>> sofia/internal/368 at 10.33.69.246 [BREAK]
>>>
>>> 2009-11-27 16:52:18.687301 [DEBUG] switch_ivr_originate.c:1054 Raw Codec
>>> Activation Success L16 at 8000hz 1 channel 90ms
>>>
>>> 2009-11-27 16:52:18.687301 [DEBUG] switch_ivr_originate.c:1116 Play
>>> Ringback Tone [%(2000,4000,440.0,480.0)]
>>>
>>> 2009-11-27 16:52:18.747333 [DEBUG] switch_core_io.c:652 sofia/internal/
>>> 368 at 10.33.69.246 receive message [TRANSCODING_NECESSARY]
>>>
>>> 2009-11-27 16:52:18.927433 [DEBUG] switch_rtp.c:1992 Correct ip/port
>>> confirmed.
>>>
>>> 2009-11-27 16:52:19.187876 [DEBUG] switch_core_io.c:402 Engaging Read
>>> Buffer at 1440 bytes vs 81
>>>
>>>
>>>
>>> *The Second Tes*t is to say to the phone 1 to transfer all the call to
>>> number 8401. So when I dial 368 on the phone 2, the phone 3 is  ringing just
>>> one time and after it hangup. I have that in the log :
>>>
>>>
>>>  2009-11-27 17:17:10.487610 [INFO] switch_ivr_originate.c:1024 Sending
>>> early media
>>>
>>> 2009-11-27 17:17:10.487610 [DEBUG] sofia_glue.c:2375 AUDIO RTP
>>> [sofia/internal/987 at 10.33.69.246] 10.33.169.92 port 27732 ->
>>> 10.33.69.144 port 32000 codec: 8 ms: 90
>>>
>>> 2009-11-27 17:17:10.487610 [DEBUG] switch_rtp.c:1155 Starting timer
>>> [soft] 720 bytes per 90ms
>>>
>>> 2009-11-27 17:17:10.497659 [INFO] mod_sofia.c:1706 Ring SDP:
>>>
>>> v=0
>>>
>>> o=FreeSWITCH 1259310898 1259310899 IN IP4 10.33.169.92
>>>
>>> s=FreeSWITCH
>>>
>>> c=IN IP4 10.33.169.92
>>>
>>> t=0 0
>>>
>>> m=audio 27732 RTP/AVP 8 106
>>>
>>> a=rtpmap:8 PCMA/8000
>>>
>>> a=rtpmap:106 telephone-event/8000
>>>
>>> a=fmtp:106 0-16
>>>
>>> a=silenceSupp:off - - - -
>>>
>>> a=ptime:90
>>>
>>> a=sendrecv
>>>
>>>
>>> 2009-11-27 17:17:10.497659 [NOTICE] mod_sofia.c:1709 Pre-Answer
>>> sofia/internal/987 at 10.33.69.246!
>>>
>>> 2009-11-27 17:17:10.497659 [DEBUG] switch_core_session.c:706 Send signal
>>> sofia/internal/sip:8401 at 10.33.170.231:5060 [BREAK]
>>>
>>> 2009-11-27 17:17:10.497659 [DEBUG] sofia.c:412 sofia/internal/
>>> sip:8401 at 10.33.170.231:5060 receive message [DISPLAY]
>>>
>>> 2009-11-27 17:17:10.497659 [DEBUG] sofia.c:3691 Channel sofia/internal/
>>> 987 at 10.33.69.246 skipping state [early][183]
>>>
>>> 2009-11-27 17:17:10.497659 [DEBUG] switch_core_session.c:645 Send signal
>>> sofia/internal/987 at 10.33.69.246 [BREAK]
>>>
>>> 2009-11-27 17:17:10.497659 [DEBUG] switch_ivr_originate.c:1054 Raw Codec
>>> Activation Success L16 at 8000hz 1 channel 90ms
>>>
>>> 2009-11-27 17:17:10.497659 [DEBUG] switch_ivr_originate.c:1116 Play
>>> Ringback Tone [%(2000,4000,440.0,480.0)]
>>>
>>> 2009-11-27 17:17:10.537273 [DEBUG] switch_core_io.c:652 sofia/internal/
>>> 987 at 10.33.69.246 receive message [TRANSCODING_NECESSARY]
>>>
>>> 2009-11-27 17:17:11.317096 [DEBUG] sofia.c:3696 Channel sofia/internal/
>>> 987 at 10.33.69.246 entering state [terminated][487]
>>>
>>> 2009-11-27 17:17:11.317096 [NOTICE] sofia.c:4299 Hangup sofia/internal/
>>> 987 at 10.33.69.246 [CS_EXECUTE] [ORIGINATOR_CANCEL]
>>>
>>> 2009-11-27 17:17:11.317096 [DEBUG] switch_channel.c:1912 Send signal
>>> sofia/internal/987 at 10.33.69.246 [KILL]
>>>
>>> 2009-11-27 17:17:11.317096 [DEBUG] switch_core_session.c:984 Send signal
>>> sofia/internal/987 at 10.33.69.246 [BREAK]
>>>
>>> 2009-11-27 17:17:11.317096 [DEBUG] switch_core_state_machine.c:459 thread
>>> mismatch skipping state handler.
>>>
>>> 2009-11-27 17:17:11.347287 [DEBUG] switch_core_codec.c:122 Restore
>>> original codec.
>>>
>>> 2009-11-27 17:17:11.347287 [NOTICE] switch_ivr_originate.c:2842 Hangup
>>> sofia/internal/sip:8401 at 10.33.170.231:5060 [CS_CONSUME_MEDIA]
>>> [ORIGINATOR_CANCEL]
>>>
>>> 2009-11-27 17:17:11.347287 [DEBUG] switch_channel.c:1912 Send signal
>>> sofia/internal/sip:8401 at 10.33.170.231:5060 [KILL]
>>>
>>> 2009-11-27 17:17:11.347287 [DEBUG] switch_core_state_machine.c:314
>>> (sofia/internal/sip:8401 at 10.33.170.231:5060) Running State Change
>>> CS_HANGUP
>>>
>>> 2009-11-27 17:17:11.347287 [DEBUG] switch_core_state_machine.c:486
>>> (sofia/internal/sip:8401 at 10.33.170.231:5060) State HANGUP
>>>
>>> 2009-11-27 17:17:11.347287 [DEBUG] mod_sofia.c:352 sofia/internal/
>>> sip:8401 at 10.33.170.231:5060 Overriding SIP cause 487 with 487 from the
>>> other leg
>>>
>>> 2009-11-27 17:17:11.347287 [DEBUG] mod_sofia.c:358 Channel
>>> sofia/internal/sip:8401 at 10.33.170.231:5060 hanging up, cause:
>>> ORIGINATOR_CANCEL
>>>
>>> 2009-11-27 17:17:11.347287 [DEBUG] mod_sofia.c:406 Sending CANCEL to
>>> sofia/internal/sip:8401 at 10.33.170.231:5060
>>>
>>> 2009-11-27 17:17:11.347287 [DEBUG] switch_core_state_machine.c:46
>>> sofia/internal/sip:8401 at 10.33.170.231:5060 Standard HANGUP, cause:
>>> ORIGINATOR_CANCEL
>>>
>>> 2009-11-27 17:17:11.347287 [DEBUG] switch_core_state_machine.c:486
>>> (sofia/internal/sip:8401 at 10.33.170.231:5060) State HANGUP going to sleep
>>>
>>> 2009-11-27 17:17:11.347287 [DEBUG] switch_core_state_machine.c:333
>>> (sofia/internal/sip:8401 at 10.33.170.231:5060) State Change CS_HANGUP ->
>>> CS_REPORTING
>>>
>>> 2009-11-27 17:17:11.347287 [DEBUG] switch_core_session.c:984 Send signal
>>> sofia/internal/sip:8401 at 10.33.170.231:5060 [BREAK]
>>>
>>> 2009-11-27 17:17:11.347287 [DEBUG] switch_core_state_machine.c:314
>>> (sofia/internal/sip:8401 at 10.33.170.231:5060) Running State Change
>>> CS_REPORTING
>>>
>>> 2009-11-27 17:17:11.347287 [DEBUG] switch_core_state_machine.c:577
>>> (sofia/internal/sip:8401 at 10.33.170.231:5060) State REPORTING
>>>
>>> 2009-11-27 17:17:11.347287 [DEBUG] switch_core_state_machine.c:53
>>> sofia/internal/sip:8401 at 10.33.170.231:5060 Standard REPORTING, cause:
>>> ORIGINATOR_CANCEL
>>>
>>> 2009-11-27 17:17:11.347287 [DEBUG] switch_core_state_machine.c:577
>>> (sofia/internal/sip:8401 at 10.33.170.231:5060) State REPORTING going to
>>> sleep
>>>
>>> 2009-11-27 17:17:11.347287 [DEBUG] switch_core_state_machine.c:327
>>> (sofia/internal/sip:8401 at 10.33.170.231:5060) State Change CS_REPORTING
>>> -> CS_DESTROY
>>>
>>> 2009-11-27 17:17:11.347287 [DEBUG] switch_core_session.c:984 Send signal
>>> sofia/internal/sip:8401 at 10.33.170.231:5060 [BREAK]
>>>
>>> 2009-11-27 17:17:11.347287 [DEBUG] switch_core_session.c:1121 Session 48
>>> (sofia/internal/sip:8401 at 10.33.170.231:5060) Locked, Waiting on external
>>> entities
>>>
>>> 2009-11-27 17:17:11.347287 [DEBUG] switch_core_session.c:984 Send signal
>>> sofia/internal/sip:8401 at 10.33.170.231:5060 [BREAK]
>>>
>>> 2009-11-27 17:17:11.347287 [DEBUG] switch_core_state_machine.c:459 thread
>>> mismatch skipping state handler.
>>>
>>> 2009-11-27 17:17:11.347287 [DEBUG] switch_ivr_originate.c:2982 Originate
>>> Cancelled by originator termination Cause: 487 [ORIGINATOR_CANCEL]
>>>
>>> 2009-11-27 17:17:11.347287 [NOTICE] switch_core_session.c:1139 Session 48
>>> (sofia/internal/sip:8401 at 10.33.170.231:5060) Ended
>>>
>>> 2009-11-27 17:17:11.347287 [NOTICE] switch_core_session.c:1141 Close
>>> Channel sofia/internal/sip:8401 at 10.33.170.231:5060 [CS_DESTROY]
>>>
>>> 2009-11-27 17:17:11.347287 [DEBUG] switch_core_state_machine.c:423
>>> (sofia/internal/sip:8401 at 10.33.170.231:5060) Running State Change
>>> CS_DESTROY
>>>
>>> 2009-11-27 17:17:11.347287 [DEBUG] switch_core_state_machine.c:434
>>> (sofia/internal/sip:8401 at 10.33.170.231:5060) State DESTROY
>>>
>>> 2009-11-27 17:17:11.347287 [DEBUG] mod_sofia.c:293 sofia/internal/
>>> sip:8401 at 10.33.170.231:5060 SOFIA DESTROY
>>>
>>> 2009-11-27 17:17:11.347287 [DEBUG] switch_core_state_machine.c:60
>>> sofia/internal/sip:8401 at 10.33.170.231:5060 Standard DESTROY
>>>
>>> 2009-11-27 17:17:11.347287 [DEBUG] switch_core_state_machine.c:434
>>> (sofia/internal/sip:8401 at 10.33.170.231:5060) State DESTROY going to
>>> sleep
>>>
>>> 2009-11-27 17:17:11.347287 [ERR] switch_ivr_originate.c:2248 Cannot
>>> create outgoing channel of type [user] cause: [ORIGINATOR_CANCEL]
>>>
>>> 2009-11-27 17:17:11.347287 [DEBUG] switch_ivr_originate.c:2988 Originate
>>> Resulted in Error Cause: 487 [ORIGINATOR_CANCEL]
>>>
>>> 2009-11-27 17:17:11.347287 [INFO] mod_dptools.c:2295 Originate Failed.
>>> Cause: ORIGINATOR_CANCEL
>>>
>>> 2009-11-27 17:17:11.347287 [DEBUG] switch_core_state_machine.c:348
>>> (sofia/internal/987 at 10.33.69.246) State EXECUTE going to sleep
>>>
>>> 2009-11-27 17:17:11.347287 [DEBUG] switch_core_state_machine.c:314
>>> (sofia/internal/987 at 10.33.69.246) Running State Change CS_HANGUP
>>>
>>> 2009-11-27 17:17:11.347287 [DEBUG] switch_core_state_machine.c:486
>>> (sofia/internal/987 at 10.33.69.246) State HANGUP
>>>
>>> 2009-11-27 17:17:11.347287 [DEBUG] mod_sofia.c:352 sofia/internal/
>>> 987 at 10.33.69.246 Overriding SIP cause 487 with 487 from the other leg
>>>
>>> 2009-11-27 17:17:11.347287 [DEBUG] mod_sofia.c:358 Channel
>>> sofia/internal/987 at 10.33.69.246 hanging up, cause: ORIGINATOR_CANCEL
>>>
>>> 2009-11-27 17:17:11.347287 [DEBUG] switch_core_state_machine.c:46
>>> sofia/internal/987 at 10.33.69.246 Standard HANGUP, cause:
>>> ORIGINATOR_CANCEL
>>>
>>> 2009-11-27 17:17:11.347287 [DEBUG] switch_core_state_machine.c:486
>>> (sofia/internal/987 at 10.33.69.246) State HANGUP going to sleep
>>>
>>> 2009-11-27 17:17:11.347287 [DEBUG] switch_core_state_machine.c:333
>>> (sofia/internal/987 at 10.33.69.246) State Change CS_HANGUP -> CS_REPORTING
>>>
>>> 2009-11-27 17:17:11.347287 [DEBUG] switch_core_session.c:984 Send signal
>>> sofia/internal/987 at 10.33.69.246 [BREAK]
>>>
>>> 2009-11-27 17:17:11.347287 [DEBUG] switch_core_state_machine.c:314
>>> (sofia/internal/987 at 10.33.69.246) Running State Change CS_REPORTING
>>>
>>> 2009-11-27 17:17:11.347287 [DEBUG] switch_core_state_machine.c:577
>>> (sofia/internal/987 at 10.33.69.246) State REPORTING
>>>
>>> 2009-11-27 17:17:11.347287 [DEBUG] switch_core_state_machine.c:53
>>> sofia/internal/987 at 10.33.69.246 Standard REPORTING, cause:
>>> ORIGINATOR_CANCEL
>>>
>>> 2009-11-27 17:17:11.347287 [DEBUG] switch_core_state_machine.c:577
>>> (sofia/internal/987 at 10.33.69.246) State REPORTING going to sleep
>>>
>>> 2009-11-27 17:17:11.347287 [DEBUG] switch_core_state_machine.c:327
>>> (sofia/internal/987 at 10.33.69.246) State Change CS_REPORTING ->
>>> CS_DESTROY
>>>
>>> 2009-11-27 17:17:11.347287 [DEBUG] switch_core_session.c:984 Send signal
>>> sofia/internal/987 at 10.33.69.246 [BREAK]
>>>
>>> 2009-11-27 17:17:11.347287 [DEBUG] switch_core_session.c:1121 Session 47
>>> (sofia/internal/987 at 10.33.69.246) Locked, Waiting on external entities
>>>
>>> 2009-11-27 17:17:11.347287 [NOTICE] switch_core_session.c:1139 Session 47
>>> (sofia/internal/987 at 10.33.69.246) Ended
>>>
>>> 2009-11-27 17:17:11.347287 [NOTICE] switch_core_session.c:1141 Close
>>> Channel sofia/internal/987 at 10.33.69.246 [CS_DESTROY]
>>>
>>> 2009-11-27 17:17:11.347287 [DEBUG] switch_core_state_machine.c:423
>>> (sofia/internal/987 at 10.33.69.246) Running State Change CS_DESTROY
>>>
>>> 2009-11-27 17:17:11.347287 [DEBUG] switch_core_state_machine.c:434
>>> (sofia/internal/987 at 10.33.69.246) State DESTROY
>>>
>>> 2009-11-27 17:17:11.347287 [DEBUG] mod_sofia.c:293 sofia/internal/
>>> 987 at 10.33.69.246 SOFIA DESTROY
>>>
>>> 2009-11-27 17:17:11.347287 [DEBUG] switch_core_state_machine.c:60
>>> sofia/internal/987 at 10.33.69.246 Standard DESTROY
>>>
>>> 2009-11-27 17:17:11.347287 [DEBUG] switch_core_state_machine.c:434
>>> (sofia/internal/987 at 10.33.69.246) State DESTROY going to sleep
>>>
>>> Finally when I tried to call the phone 3 with the phone 1 it's working,
>>> and not when I want to call the phone 3 with the phone 2, like just before,
>>> it's ringing just one time and hangup.
>>>
>>>
>>> Thanks you.
>>>
>>>
>>> Best Regards
>>>
>>> --
>>> John
>>>
>>>
>>>
>>> _______________________________________________
>>> 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
>>>
>>>
>>
>>
>> --
>> Anthony Minessale II
>>
>> FreeSWITCH http://www.freeswitch.org/
>> ClueCon http://www.cluecon.com/
>> Twitter: http://twitter.com/FreeSWITCH_wire
>>
>> AIM: anthm
>> MSN:anthony_minessale at hotmail.com <MSN%3Aanthony_minessale at hotmail.com>
>> GTALK/JABBER/PAYPAL:anthony.minessale at gmail.com<PAYPAL%3Aanthony.minessale at gmail.com>
>> IRC: irc.freenode.net #freeswitch
>>
>> FreeSWITCH Developer Conference
>> sip:888 at conference.freeswitch.org <sip%3A888 at conference.freeswitch.org>
>> iax:guest at conference.freeswitch.org/888
>> googletalk:conf+888 at conference.freeswitch.org<googletalk%3Aconf%2B888 at conference.freeswitch.org>
>> pstn:213-799-1400
>>
>> _______________________________________________
>> 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
>>
>>
>
>
> --
> Jonathan BAROU
> Groupe SQLI - CRCI
>
> 0472405368
> jbarou at sqli.com
>
> 1, place Verrazzano
> 69258 LYON CEDEX 09
>
>


-- 
Jonathan BAROU
Groupe SQLI - CRCI

0472405368
jbarou at sqli.com
1, place Verrazzano
69258 LYON CEDEX 09
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20091202/2109ac3f/attachment-0002.html

More information about the FreeSWITCH-users mailing list