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@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@10.33.69.246!

2009-11-27 16:52:18.687301 [DEBUG] switch_core_session.c:706 Send signal sofia/internal/sip:8401@10.33.170.231:5060 [BREAK]

2009-11-27 16:52:18.687301 [DEBUG] sofia.c:412 sofia/internal/sip:8401@10.33.170.231:5060 receive message [DISPLAY]

2009-11-27 16:52:18.687301 [DEBUG] sofia.c:3691 Channel sofia/internal/368@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@10.33.69.246 [BREAK]

2009-11-27 16:52:18.687301 [DEBUG] switch_ivr_originate.c:1054 Raw Codec Activation Success L16@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@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 Test 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@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@10.33.69.246!

2009-11-27 17:17:10.497659 [DEBUG] switch_core_session.c:706 Send signal sofia/internal/sip:8401@10.33.170.231:5060 [BREAK]

2009-11-27 17:17:10.497659 [DEBUG] sofia.c:412 sofia/internal/sip:8401@10.33.170.231:5060 receive message [DISPLAY]

2009-11-27 17:17:10.497659 [DEBUG] sofia.c:3691 Channel sofia/internal/987@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@10.33.69.246 [BREAK]

2009-11-27 17:17:10.497659 [DEBUG] switch_ivr_originate.c:1054 Raw Codec Activation Success L16@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@10.33.69.246 receive message [TRANSCODING_NECESSARY]

2009-11-27 17:17:11.317096 [DEBUG] sofia.c:3696 Channel sofia/internal/987@10.33.69.246 entering state [terminated][487]

2009-11-27 17:17:11.317096 [NOTICE] sofia.c:4299 Hangup sofia/internal/987@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@10.33.69.246 [KILL]

2009-11-27 17:17:11.317096 [DEBUG] switch_core_session.c:984 Send signal sofia/internal/987@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@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@10.33.170.231:5060 [KILL]

2009-11-27 17:17:11.347287 [DEBUG] switch_core_state_machine.c:314 (sofia/internal/sip:8401@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@10.33.170.231:5060) State HANGUP

2009-11-27 17:17:11.347287 [DEBUG] mod_sofia.c:352 sofia/internal/sip:8401@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@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@10.33.170.231:5060

2009-11-27 17:17:11.347287 [DEBUG] switch_core_state_machine.c:46 sofia/internal/sip:8401@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@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@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@10.33.170.231:5060 [BREAK]

2009-11-27 17:17:11.347287 [DEBUG] switch_core_state_machine.c:314 (sofia/internal/sip:8401@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@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@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@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@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@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@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@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@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@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@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@10.33.170.231:5060) State DESTROY

2009-11-27 17:17:11.347287 [DEBUG] mod_sofia.c:293 sofia/internal/sip:8401@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@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@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@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@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@10.33.69.246) State HANGUP

2009-11-27 17:17:11.347287 [DEBUG] mod_sofia.c:352 sofia/internal/987@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@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@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@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@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@10.33.69.246 [BREAK]

2009-11-27 17:17:11.347287 [DEBUG] switch_core_state_machine.c:314 (sofia/internal/987@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@10.33.69.246) State REPORTING

2009-11-27 17:17:11.347287 [DEBUG] switch_core_state_machine.c:53 sofia/internal/987@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@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@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@10.33.69.246 [BREAK]

2009-11-27 17:17:11.347287 [DEBUG] switch_core_session.c:1121 Session 47 (sofia/internal/987@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@10.33.69.246) Ended

2009-11-27 17:17:11.347287 [NOTICE] switch_core_session.c:1141 Close Channel sofia/internal/987@10.33.69.246 [CS_DESTROY]

2009-11-27 17:17:11.347287 [DEBUG] switch_core_state_machine.c:423 (sofia/internal/987@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@10.33.69.246) State DESTROY

2009-11-27 17:17:11.347287 [DEBUG] mod_sofia.c:293 sofia/internal/987@10.33.69.246 SOFIA DESTROY

2009-11-27 17:17:11.347287 [DEBUG] switch_core_state_machine.c:60 sofia/internal/987@10.33.69.246 Standard DESTROY

2009-11-27 17:17:11.347287 [DEBUG] switch_core_state_machine.c:434 (sofia/internal/987@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