[Freeswitch-users] Transfer Problem

Anthony Minessale anthony.minessale at gmail.com
Fri Nov 27 09:03:10 PST 2009


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


More information about the FreeSWITCH-users mailing list