[Freeswitch-users] Transfer Problem

Jonathan Barou jbarou at sqli.com
Mon Nov 30 00:33:13 PST 2009 

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
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20091130/79f5ecb6/attachment-0002.html

More information about the FreeSWITCH-users mailing list