run sofia profile internal siptrace on and repeat so you can see the sip traffic. On Sat, Apr 3, 2010 at 9:14 AM, Jan Riedinger <riedinger@sns.eu> wrote:
If I dial in to a conference from my Cisco IP Phone 7940 all is working as it should.
However, if I dial in via a voip carrier, the calls are disconnect with disconnect cause "facility rejected" by the voip carrier. It seems that Freeswitch tries to change some parameters of the call setup, which is refused. You find below the console log output. Do you have an idea, what I have to change to get it working?
I know that it is working in prinicple, because I can setup outgoing conferences via the voip carrier.
Thank you very much in advance Jan
=~=~=~=~=~=~=~=~=~=~=~= PuTTY log 2010.04.03 16:56:10 =~=~=~=~=~=~=~=~=~=~=~= [36m2010-04-03 16:56:07.593334 [NOTICE] switch_channel.c:669 New Channel sofia/external/49XXXXXXX6@XXX.XX.XX.X6 [27446b50-dc2a-4088-83f2-7b7cc8f014e8] [m [33m2010-04-03 16:56:07.593334 [DEBUG] sofia.c:4153 Channel sofia/external/49XXXXXXX6@XXX.XX.XX.X6 entering state [received][100] [m [33m2010-04-03 16:56:07.593334 [DEBUG] sofia.c:4164 Remote SDP: v=0
o=- 1270306567 1270306567 IN IP4 XXX.XX.XX.X6
s=-
c=IN IP4 XXX.XX.XX.X6
t=0 0
m=audio 19188 RTP/AVP 18 4 8 0 101
a=rtpmap:18 G729/8000
a=rtpmap:4 G723/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
[m [33m2010-04-03 16:56:07.593334 [DEBUG] sofia_glue.c:3585 Audio Codec Compare [G729:18:8000:20]/[G7221:115:32000:20] [m [33m2010-04-03 16:56:07.593334 [DEBUG] sofia_glue.c:3585 Audio Codec Compare [G729:18:8000:20]/[G7221:107:16000:20] [m [33m2010-04-03 16:56:07.593334 [DEBUG] sofia_glue.c:3585 Audio Codec Compare [G729:18:8000:20]/[G722:9:8000:20] [m [33m2010-04-03 16:56:07.593334 [DEBUG] sofia_glue.c:3585 Audio Codec Compare [G729:18:8000:20]/[PCMU:0:8000:20] [m [33m2010-04-03 16:56:07.593334 [DEBUG] sofia_glue.c:3585 Audio Codec Compare [G729:18:8000:20]/[PCMA:8:8000:20] [m [33m2010-04-03 16:56:07.593334 [DEBUG] sofia_glue.c:3585 Audio Codec Compare [G729:18:8000:20]/[GSM:3:8000:20] [m [33m2010-04-03 16:56:07.593334 [DEBUG] sofia_glue.c:3585 Audio Codec Compare [G723:4:8000:20]/[G7221:115:32000:20] [m [33m2010-04-03 16:56:07.593334 [DEBUG] sofia_glue.c:3585 Audio Codec Compare [G723:4:8000:20]/[G7221:107:16000:20] [m [33m2010-04-03 16:56:07.593334 [DEBUG] sofia_glue.c:3585 Audio Codec Compare [G723:4:8000:20]/[G722:9:8000:20] [m [33m2010-04-03 16:56:07.593334 [DEBUG] sofia_glue.c:3585 Audio Codec Compare [G723:4:8000:20]/[PCMU:0:8000:20] [m [33m2010-04-03 16:56:07.593334 [DEBUG] sofia_glue.c:3585 Audio Codec Compare [G723:4:8000:20]/[PCMA:8:8000:20] [m [33m2010-04-03 16:56:07.593334 [DEBUG] sofia_glue.c:3585 Audio Codec Compare [G723:4:8000:20]/[GSM:3:8000:20] [m [33m2010-04-03 16:56:07.593334 [DEBUG] sofia_glue.c:3585 Audio Codec Compare [PCMA:8:8000:20]/[G7221:115:32000:20] [m [33m2010-04-03 16:56:07.593334 [DEBUG] sofia_glue.c:3585 Audio Codec Compare [PCMA:8:8000:20]/[G7221:107:16000:20] [m [33m2010-04-03 16:56:07.593334 [DEBUG] sofia_glue.c:3585 Audio Codec Compare [PCMA:8:8000:20]/[G722:9:8000:20] [m [33m2010-04-03 16:56:07.593334 [DEBUG] sofia_glue.c:3585 Audio Codec Compare [PCMA:8:8000:20]/[PCMU:0:8000:20] [m [33m2010-04-03 16:56:07.593334 [DEBUG] sofia_glue.c:3585 Audio Codec Compare [PCMA:8:8000:20]/[PCMA:8:8000:20] [m [33m2010-04-03 16:56:07.593334 [DEBUG] sofia_glue.c:2354 Set Codec sofia/external/49XXXXXXX6@XXX.XX.XX.X6 PCMA/8000 20 ms 160 samples [m [33m2010-04-03 16:56:07.593334 [DEBUG] sofia_glue.c:3524 Set 2833 dtmf send/recv payload to 101 [m [33m2010-04-03 16:56:07.593334 [DEBUG] sofia.c:4310 (sofia/external/49XXXXXXX6@XXX.XX.XX.X6) State Change CS_NEW -> CS_INIT [m [33m2010-04-03 16:56:07.593334 [DEBUG] switch_core_session.c:1018 Send signal sofia/external/49XXXXXXX6@XXX.XX.XX.X6 [BREAK] [m [33m2010-04-03 16:56:07.593334 [DEBUG] switch_core_state_machine.c:314 (sofia/external/49XXXXXXX6@XXX.XX.XX.X6) Running State Change CS_INIT [m [33m2010-04-03 16:56:07.593334 [DEBUG] switch_core_state_machine.c:338 (sofia/external/49XXXXXXX6@XXX.XX.XX.X6) State INIT [m [33m2010-04-03 16:56:07.593334 [DEBUG] mod_sofia.c:83 sofia/external/49XXXXXXX6@XXX.XX.XX.X6 SOFIA INIT [m [33m2010-04-03 16:56:07.593334 [DEBUG] mod_sofia.c:117 (sofia/external/49XXXXXXX6@XXX.XX.XX.X6) State Change CS_INIT -> CS_ROUTING [m [33m2010-04-03 16:56:07.593334 [DEBUG] switch_core_session.c:1018 Send signal sofia/external/49XXXXXXX6@XXX.XX.XX.X6 [BREAK] [m [33m2010-04-03 16:56:07.593334 [DEBUG] switch_core_state_machine.c:338 (sofia/external/49XXXXXXX6@XXX.XX.XX.X6) State INIT going to sleep [m [33m2010-04-03 16:56:07.593334 [DEBUG] switch_core_state_machine.c:314 (sofia/external/49XXXXXXX6@XXX.XX.XX.X6) Running State Change CS_ROUTING [m [33m2010-04-03 16:56:07.593334 [DEBUG] switch_core_state_machine.c:341 (sofia/external/49XXXXXXX6@XXX.XX.XX.X6) State ROUTING [m [33m2010-04-03 16:56:07.593334 [DEBUG] mod_sofia.c:140 sofia/external/49XXXXXXX6@XXX.XX.XX.X6 SOFIA ROUTING [m [33m2010-04-03 16:56:07.593334 [DEBUG] switch_core_state_machine.c:77 sofia/external/49XXXXXXX6@XXX.XX.XX.X6 Standard ROUTING [m [32m2010-04-03 16:56:07.593334 [INFO] mod_dialplan_xml.c:418 Processing 49XXXXXXX6->49331YYYYYY in context public [m [33mDialplan: sofia/external/49XXXXXXX6@XXX.XX.XX.X6 parsing [public->unloop] continue=false [m [33mDialplan: sofia/external/49XXXXXXX6@XXX.XX.XX.X6 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false [m [33mDialplan: sofia/external/49XXXXXXX6@XXX.XX.XX.X6 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false [m [33mDialplan: sofia/external/49XXXXXXX6@XXX.XX.XX.X6 parsing [public->outside_call] continue=true [m [33mDialplan: sofia/external/49XXXXXXX6@XXX.XX.XX.X6 Absolute Condition [outside_call] [m [33mDialplan: sofia/external/49XXXXXXX6@XXX.XX.XX.X6 Action set(outside_call=true) [m [33mDialplan: sofia/external/49XXXXXXX6@XXX.XX.XX.X6 parsing [public->call_debug] continue=true [m [33mDialplan: sofia/external/49XXXXXXX6@XXX.XX.XX.X6 Regex (FAIL) [call_debug] ${call_debug}(false) =~ /^true$/ break=never ... [m [33mDialplan: sofia/external/49XXXXXXX6@XXX.XX.XX.X6 parsing [public->sns_conference] continue=false [m [33mDialplan: sofia/external/49XXXXXXX6@XXX.XX.XX.X6 Regex (PASS) [sns_conference] destination_number(49331YYYYYY) =~ /^(49331YYYYYY)$/ break=on-false [m [33mDialplan: sofia/external/49XXXXXXX6@XXX.XX.XX.X6 Action answer() [m [33mDialplan: sofia/external/49XXXXXXX6@XXX.XX.XX.X6 Action set(conference_enforce_security=false) [m [33mDialplan: sofia/external/49XXXXXXX6@XXX.XX.XX.X6 Action conference(49331YYYYYY-${domain_name}@default) [m [33m2010-04-03 16:56:07.596335 [DEBUG] switch_core_state_machine.c:119 (sofia/external/49XXXXXXX6@XXX.XX.XX.X6) State Change CS_ROUTING -> CS_EXECUTE [m [33m2010-04-03 16:56:07.596335 [DEBUG] switch_core_session.c:1018 Send signal sofia/external/49XXXXXXX6@XXX.XX.XX.X6 [BREAK] [m [33m2010-04-03 16:56:07.596335 [DEBUG] switch_core_state_machine.c:341 (sofia/external/49XXXXXXX6@XXX.XX.XX.X6) State ROUTING going to sleep [m [33m2010-04-03 16:56:07.596335 [DEBUG] switch_core_state_machine.c:314 (sofia/external/49XXXXXXX6@XXX.XX.XX.X6) Running State Change CS_EXECUTE [m [33m2010-04-03 16:56:07.596335 [DEBUG] switch_core_state_machine.c:348 (sofia/external/49XXXXXXX6@XXX.XX.XX.X6) State EXECUTE [m [33m2010-04-03 16:56:07.596335 [DEBUG] mod_sofia.c:226 sofia/external/49XXXXXXX6@XXX.XX.XX.X6 SOFIA EXECUTE [m [33m2010-04-03 16:56:07.596335 [DEBUG] switch_core_state_machine.c:157 sofia/external/49XXXXXXX6@XXX.XX.XX.X6 Standard EXECUTE [m [33mEXECUTE sofia/external/49XXXXXXX6@XXX.XX.XX.X6 set(outside_call=true) [m [33m2010-04-03 16:56:07.596335 [DEBUG] mod_dptools.c:816 sofia/external/49XXXXXXX6@XXX.XX.XX.X6 SET [outside_call]=[true] [m [33mEXECUTE sofia/external/49XXXXXXX6@XXX.XX.XX.X6 answer() [m [33m2010-04-03 16:56:07.596335 [DEBUG] sofia_glue.c:2594 AUDIO RTP [sofia/external/49XXXXXXX6@XXX.XX.XX.X6] XXX.XX.XX.X7 port 17138 -> XXX.XX.XX.X6 port 19188 codec: 8 ms: 20 [m [33m2010-04-03 16:56:07.596335 [DEBUG] switch_rtp.c:1182 Starting timer [soft] 160 bytes per 20ms [m [33m2010-04-03 16:56:07.599335 [DEBUG] sofia_glue.c:2774 Set 2833 dtmf send payload to 101 [m [33m2010-04-03 16:56:07.599335 [DEBUG] sofia_glue.c:2779 Set 2833 dtmf receive payload to 101 [m [33m2010-04-03 16:56:07.599335 [DEBUG] mod_sofia.c:636 Local SDP sofia/external/49XXXXXXX6@XXX.XX.XX.X6: v=0 o=FreeSWITCH 1270289429 1270289430 IN IP4 XXX.XX.XX.X7 s=FreeSWITCH c=IN IP4 XXX.XX.XX.X7 t=0 0 m=audio 17138 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 a=sendrecv
[m [33m2010-04-03 16:56:07.599335 [DEBUG] sofia.c:4153 Channel sofia/external/49XXXXXXX6@XXX.XX.XX.X6 entering state [completed][200] [m [33m2010-04-03 16:56:07.599335 [DEBUG] switch_core_session.c:638 Send signal sofia/external/49XXXXXXX6@XXX.XX.XX.X6 [BREAK] [m [36m2010-04-03 16:56:07.599335 [NOTICE] mod_dptools.c:719 Channel [sofia/external/49XXXXXXX6@XXX.XX.XX.X6] has been answered [m [33mEXECUTE sofia/external/49XXXXXXX6@XXX.XX.XX.X6 set(conference_enforce_security=false) [m [33m2010-04-03 16:56:07.599335 [DEBUG] mod_dptools.c:816 sofia/external/49XXXXXXX6@XXX.XX.XX.X6 SET [conference_enforce_security]=[false] [m [33mEXECUTE sofia/external/49XXXXXXX6@XXX.XX.XX.X6 conference(49331YYYYYY-XXX.XX.XX.X7@default) [m [33m2010-04-03 16:56:07.599335 [DEBUG] mod_conference.c:5671 Installing default caller control action 'mute' bound to '0'. [m [33m2010-04-03 16:56:07.599335 [DEBUG] switch_ivr.c:1616 max len 1 [m [33m2010-04-03 16:56:07.599335 [DEBUG] switch_ivr.c:1620 min len 1 [m [33m2010-04-03 16:56:07.599335 [DEBUG] mod_conference.c:5671 Installing default caller control action 'deaf mute' bound to '*'. [m [33m2010-04-03 16:56:07.599335 [DEBUG] mod_conference.c:5671 Installing default caller control action 'energy up' bound to '9'. [m [33m2010-04-03 16:56:07.599335 [DEBUG] mod_conference.c:5671 Installing default caller control action 'energy equ' bound to '8'. [m [33m2010-04-03 16:56:07.599335 [DEBUG] mod_conference.c:5671 Installing default caller control action 'energy dn' bound to '7'. [m [33m2010-04-03 16:56:07.599335 [DEBUG] mod_conference.c:5671 Installing default caller control action 'vol talk up' bound to '3'. [m [33m2010-04-03 16:56:07.599335 [DEBUG] mod_conference.c:5671 Installing default caller control action 'vol talk zero' bound to '2'. [m [33m2010-04-03 16:56:07.599335 [DEBUG] mod_conference.c:5671 Installing default caller control action 'vol talk dn' bound to '1'. [m [33m2010-04-03 16:56:07.599335 [DEBUG] mod_conference.c:5671 Installing default caller control action 'vol listen up' bound to '6'. [m [33m2010-04-03 16:56:07.599335 [DEBUG] mod_conference.c:5671 Installing default caller control action 'vol listen zero' bound to '5'. [m [33m2010-04-03 16:56:07.599335 [DEBUG] mod_conference.c:5671 Installing default caller control action 'vol listen dn' bound to '4'. [m [33m2010-04-03 16:56:07.599335 [DEBUG] mod_conference.c:5671 Installing default caller control action 'hangup' bound to '#'. [m [33m2010-04-03 16:56:07.599335 [DEBUG] mod_conference.c:4990 Raw Codec Activation Success L16@8000hz 1 channel 20ms [m [33m2010-04-03 16:56:07.599335 [DEBUG] mod_conference.c:5035 Raw Codec Activation Success L16@8000hz 1 channel 20ms [m [33m2010-04-03 16:56:07.599335 [DEBUG] switch_core_codec.c:122 sofia/external/49XXXXXXX6@XXX.XX.XX.X6 Push codec L16:10 [m [33m2010-04-03 16:56:07.599335 [DEBUG] mod_conference.c:994 Setup timer success interval: 20 samples: 160 [m [33m2010-04-03 16:56:07.599335 [DEBUG] switch_core_session.c:638 Send signal sofia/external/49XXXXXXX6@XXX.XX.XX.X6 [BREAK] [m [33m2010-04-03 16:56:07.599335 [DEBUG] mod_conference.c:2202 Setup timer soft success interval: 20 samples: 160 [m [33m2010-04-03 16:56:07.617340 [DEBUG] sofia.c:4153 Channel sofia/external/49XXXXXXX6@XXX.XX.XX.X6 entering state [ready][200] [m [36m2010-04-03 16:56:07.839332 [NOTICE] sofia.c:481 Hangup sofia/external/49XXXXXXX6@XXX.XX.XX.X6 [CS_EXECUTE] [FACILITY_REJECTED] [m [33m2010-04-03 16:56:07.839332 [DEBUG] switch_channel.c:2071 Send signal sofia/external/49XXXXXXX6@XXX.XX.XX.X6 [KILL] [m [33m2010-04-03 16:56:07.839332 [DEBUG] switch_core_session.c:1018 Send signal sofia/external/49XXXXXXX6@XXX.XX.XX.X6 [BREAK] [m [33m2010-04-03 16:56:07.848341 [DEBUG] mod_conference.c:2473 Channel leaving conference, cause: FACILITY_REJECTED [m [33m2010-04-03 16:56:07.851332 [DEBUG] switch_core_codec.c:146 sofia/external/49XXXXXXX6@XXX.XX.XX.X6 Restore previous codec PCMA:8. [m [33m2010-04-03 16:56:07.851332 [DEBUG] switch_core_state_machine.c:348 (sofia/external/49XXXXXXX6@XXX.XX.XX.X6) State EXECUTE going to sleep [m [33m2010-04-03 16:56:07.851332 [DEBUG] switch_core_state_machine.c:314 (sofia/external/49XXXXXXX6@XXX.XX.XX.X6) Running State Change CS_HANGUP [m [33m2010-04-03 16:56:07.851332 [DEBUG] switch_core_state_machine.c:499 (sofia/external/49XXXXXXX6@XXX.XX.XX.X6) State HANGUP [m [33m2010-04-03 16:56:07.851332 [DEBUG] mod_sofia.c:408 sofia/external/49XXXXXXX6@XXX.XX.XX.X6 Overriding SIP cause 501 with 200 from the other leg [m [33m2010-04-03 16:56:07.851332 [DEBUG] mod_sofia.c:414 Channel sofia/external/49XXXXXXX6@XXX.XX.XX.X6 hanging up, cause: FACILITY_REJECTED [m [33m2010-04-03 16:56:07.851332 [DEBUG] switch_core_state_machine.c:46 sofia/external/49XXXXXXX6@XXX.XX.XX.X6 Standard HANGUP, cause: FACILITY_REJECTED [m [33m2010-04-03 16:56:07.851332 [DEBUG] switch_core_state_machine.c:499 (sofia/external/49XXXXXXX6@XXX.XX.XX.X6) State HANGUP going to sleep [m [33m2010-04-03 16:56:07.851332 [DEBUG] switch_core_state_machine.c:333 (sofia/external/49XXXXXXX6@XXX.XX.XX.X6) State Change CS_HANGUP -> CS_REPORTING [m [33m2010-04-03 16:56:07.851332 [DEBUG] switch_core_session.c:1018 Send signal sofia/external/49XXXXXXX6@XXX.XX.XX.X6 [BREAK] [m [33m2010-04-03 16:56:07.851332 [DEBUG] switch_core_state_machine.c:314 (sofia/external/49XXXXXXX6@XXX.XX.XX.X6) Running State Change CS_REPORTING [m [33m2010-04-03 16:56:07.851332 [DEBUG] switch_core_state_machine.c:590 (sofia/external/49XXXXXXX6@XXX.XX.XX.X6) State REPORTING [m [33m2010-04-03 16:56:07.851332 [DEBUG] switch_core_state_machine.c:53 sofia/external/49XXXXXXX6@XXX.XX.XX.X6 Standard REPORTING, cause: FACILITY_REJECTED [m [33m2010-04-03 16:56:07.851332 [DEBUG] switch_core_state_machine.c:590 (sofia/external/49XXXXXXX6@XXX.XX.XX.X6) State REPORTING going to sleep [m [33m2010-04-03 16:56:07.851332 [DEBUG] switch_core_state_machine.c:327 (sofia/external/49XXXXXXX6@XXX.XX.XX.X6) State Change CS_REPORTING -> CS_DESTROY [m [33m2010-04-03 16:56:07.851332 [DEBUG] switch_core_session.c:1018 Send signal sofia/external/49XXXXXXX6@XXX.XX.XX.X6 [BREAK] [m [33m2010-04-03 16:56:07.851332 [DEBUG] switch_core_session.c:1161 Session 7 (sofia/external/49XXXXXXX6@XXX.XX.XX.X6) Locked, Waiting on external entities [m [36m2010-04-03 16:56:07.851332 [NOTICE] switch_core_session.c:1179 Session 7 (sofia/external/49XXXXXXX6@XXX.XX.XX.X6) Ended [m [36m2010-04-03 16:56:07.851332 [NOTICE] switch_core_session.c:1181 Close Channel sofia/external/49XXXXXXX6@XXX.XX.XX.X6 [CS_DESTROY] [m [33m2010-04-03 16:56:07.851332 [DEBUG] switch_core_state_machine.c:428 (sofia/external/49XXXXXXX6@XXX.XX.XX.X6) Running State Change CS_DESTROY [m [33m2010-04-03 16:56:07.851332 [DEBUG] switch_core_state_machine.c:439 (sofia/external/49XXXXXXX6@XXX.XX.XX.X6) State DESTROY [m [33m2010-04-03 16:56:07.851332 [DEBUG] mod_sofia.c:341 sofia/external/49XXXXXXX6@XXX.XX.XX.X6 SOFIA DESTROY [m [33m2010-04-03 16:56:07.851332 [DEBUG] switch_core_state_machine.c:60 sofia/external/49XXXXXXX6@XXX.XX.XX.X6 Standard DESTROY [m [33m2010-04-03 16:56:07.851332 [DEBUG] switch_core_state_machine.c:439 (sofia/external/49XXXXXXX6@XXX.XX.XX.X6) State DESTROY going to sleep [m [33m2010-04-03 16:56:07.866331 [DEBUG] mod_conference.c:1361 Write Lock ON [m [33m2010-04-03 16:56:07.866331 [DEBUG] mod_conference.c:1364 Write Lock OFF
-- Jan Riedinger Phone : +49-30-39 73 19 66 Dipl.-Inf. | Managing Director Fax : +49-30-39 73 19 64 E-Mail: riedinger@sns.eu SNS Consult GmbH ICQ : 163-237-041 Südwestkorso 49a MSN : jan@sns-consult.de 14197 Berlin GERMANY Skype : Jan Riedinger
AG Charlottenburg - HRB 71973
_______________________________________________ FreeSWITCH-users mailing list FreeSWITCH-users@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@hotmail.com <MSN%3Aanthony_minessale@hotmail.com> GTALK/JABBER/PAYPAL:anthony.minessale@gmail.com<PAYPAL%3Aanthony.minessale@gmail.com> IRC: irc.freenode.net #freeswitch FreeSWITCH Developer Conference sip:888@conference.freeswitch.org <sip%3A888@conference.freeswitch.org> googletalk:conf+888@conference.freeswitch.org<googletalk%3Aconf%2B888@conference.freeswitch.org> pstn:+19193869900