Conference Dial In Isn't Working (mod_conference)
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
sofia profile xxxxx siptrace on (replace xxx with the profile) Then try again. /b On Apr 3, 2010, at 10:14 AM, Jan Riedinger wrote:
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?
Here is the missing debug information. Thanks Jan freeswitch@...> recv 839 bytes from udp/[XXX.XX.XX.X6]:5060 at 16:48:12.118650: ------------------------------------------------------------------------ INVITE sip:49331YYYYYY@XXX.XX.XX.X7:5060;user=phone SIP/2.0 Via: SIP/2.0/UDP XXX.XX.XX.X6:5060;branch=z9hG4bK-34ff11004c71ff10ff000024ff0c6dff From: <sip:49XXXXXXXX6@XXX.XX.XX.X6;user=phone>;tag=44ff11004c71ff10ff000024ff0c6dff To: <sip:49331YYYYYY@XXX.XX.XX.X7:5060;user=phone> Call-ID: 9aef11004c71b710800000248c0c6d9e@DOMAIN_ORIG CSeq: 1 INVITE Contact: <sip:49XXXXXXXX6@XXX.XX.XX.X6;user=phone> Max-Forwards: 10 User-Agent: MERA MSIP v.1.0.2 Cisco-Guid: 2962728880-1061163487-2855600256-2188435832 Content-Type: application/sdp Content-Length: 264 v=0 o=- 1270313292 1270313292 IN IP4 XXX.XX.XX.X6 s=- c=IN IP4 XXX.XX.XX.X6 t=0 0 m=audio 22284 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 ------------------------------------------------------------------------ send 392 bytes to udp/[XXX.XX.XX.X6]:5060 at 16:48:12.118936: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP XXX.XX.XX.X6:5060;branch=z9hG4bK-34ff11004c71ff10ff000024ff0c6dff From: <sip:49XXXXXXXX6@XXX.XX.XX.X6;user=phone>;tag=44ff11004c71ff10ff000024ff0c6dff To: <sip:49331YYYYYY@XXX.XX.XX.X7:5060;user=phone> Call-ID: 9aef11004c71b710800000248c0c6d9e@DOMAIN_ORIG CSeq: 1 INVITE User-Agent: FreeSWITCH-mod_sofia/1.0.git-exportiert Content-Length: 0 ------------------------------------------------------------------------ 2010-04-03 18:48:12.116629 [NOTICE] switch_channel.c:669 New Channel sofia/external/49XXXXXXXX6@XXX.XX.XX.X6 [d1242848-5588-4083-acea-062fd74e5f66] 2010-04-03 18:48:12.116629 [DEBUG] sofia.c:4153 Channel sofia/external/49XXXXXXXX6@XXX.XX.XX.X6 entering state [received][100] 2010-04-03 18:48:12.116629 [DEBUG] sofia.c:4164 Remote SDP: v=0 o=- 1270313292 1270313292 IN IP4 XXX.XX.XX.X6 s=- c=IN IP4 XXX.XX.XX.X6 t=0 0 m=audio 22284 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 2010-04-03 18:48:12.119630 [DEBUG] sofia_glue.c:3585 Audio Codec Compare [G729:18:8000:20]/[G7221:115:32000:20] 2010-04-03 18:48:12.119630 [DEBUG] sofia_glue.c:3585 Audio Codec Compare [G729:18:8000:20]/[G7221:107:16000:20] 2010-04-03 18:48:12.119630 [DEBUG] sofia_glue.c:3585 Audio Codec Compare [G729:18:8000:20]/[G722:9:8000:20] 2010-04-03 18:48:12.119630 [DEBUG] sofia_glue.c:3585 Audio Codec Compare [G729:18:8000:20]/[PCMU:0:8000:20] 2010-04-03 18:48:12.119630 [DEBUG] sofia_glue.c:3585 Audio Codec Compare [G729:18:8000:20]/[PCMA:8:8000:20] 2010-04-03 18:48:12.119630 [DEBUG] switch_core_state_machine.c:314 (sofia/external/49XXXXXXXX6@XXX.XX.XX.X6) Running State Change CS_NEW 2010-04-03 18:48:12.119630 [DEBUG] sofia_glue.c:3585 Audio Codec Compare [G729:18:8000:20]/[GSM:3:8000:20] 2010-04-03 18:48:12.119630 [DEBUG] sofia_glue.c:3585 Audio Codec Compare [G723:4:8000:20]/[G7221:115:32000:20] 2010-04-03 18:48:12.119630 [DEBUG] sofia_glue.c:3585 Audio Codec Compare [G723:4:8000:20]/[G7221:107:16000:20] 2010-04-03 18:48:12.119630 [DEBUG] sofia_glue.c:3585 Audio Codec Compare [G723:4:8000:20]/[G722:9:8000:20] 2010-04-03 18:48:12.119630 [DEBUG] sofia_glue.c:3585 Audio Codec Compare [G723:4:8000:20]/[PCMU:0:8000:20] 2010-04-03 18:48:12.119630 [DEBUG] sofia_glue.c:3585 Audio Codec Compare [G723:4:8000:20]/[PCMA:8:8000:20] 2010-04-03 18:48:12.119630 [DEBUG] sofia_glue.c:3585 Audio Codec Compare [G723:4:8000:20]/[GSM:3:8000:20] 2010-04-03 18:48:12.119630 [DEBUG] sofia_glue.c:3585 Audio Codec Compare [PCMA:8:8000:20]/[G7221:115:32000:20] 2010-04-03 18:48:12.119630 [DEBUG] sofia_glue.c:3585 Audio Codec Compare [PCMA:8:8000:20]/[G7221:107:16000:20] 2010-04-03 18:48:12.119630 [DEBUG] sofia_glue.c:3585 Audio Codec Compare [PCMA:8:8000:20]/[G722:9:8000:20] 2010-04-03 18:48:12.119630 [DEBUG] sofia_glue.c:3585 Audio Codec Compare [PCMA:8:8000:20]/[PCMU:0:8000:20] 2010-04-03 18:48:12.119630 [DEBUG] sofia_glue.c:3585 Audio Codec Compare [PCMA:8:8000:20]/[PCMA:8:8000:20] 2010-04-03 18:48:12.119630 [DEBUG] sofia_glue.c:2354 Set Codec sofia/external/49XXXXXXXX6@XXX.XX.XX.X6 PCMA/8000 20 ms 160 samples 2010-04-03 18:48:12.119630 [DEBUG] sofia_glue.c:3524 Set 2833 dtmf send/recv payload to 101 2010-04-03 18:48:12.119630 [DEBUG] sofia.c:4310 (sofia/external/49XXXXXXXX6@XXX.XX.XX.X6) State Change CS_NEW -> CS_INIT 2010-04-03 18:48:12.119630 [DEBUG] switch_core_session.c:1018 Send signal sofia/external/49XXXXXXXX6@XXX.XX.XX.X6 [BREAK] 2010-04-03 18:48:12.119630 [DEBUG] switch_core_state_machine.c:320 (sofia/external/49XXXXXXXX6@XXX.XX.XX.X6) State NEW 2010-04-03 18:48:12.119630 [DEBUG] switch_core_state_machine.c:314 (sofia/external/49XXXXXXXX6@XXX.XX.XX.X6) Running State Change CS_INIT 2010-04-03 18:48:12.119630 [DEBUG] switch_core_state_machine.c:338 (sofia/external/49XXXXXXXX6@XXX.XX.XX.X6) State INIT 2010-04-03 18:48:12.119630 [DEBUG] mod_sofia.c:83 sofia/external/49XXXXXXXX6@XXX.XX.XX.X6 SOFIA INIT 2010-04-03 18:48:12.119630 [DEBUG] mod_sofia.c:117 (sofia/external/49XXXXXXXX6@XXX.XX.XX.X6) State Change CS_INIT -> CS_ROUTING 2010-04-03 18:48:12.119630 [DEBUG] switch_core_session.c:1018 Send signal sofia/external/49XXXXXXXX6@XXX.XX.XX.X6 [BREAK] 2010-04-03 18:48:12.119630 [DEBUG] switch_core_state_machine.c:338 (sofia/external/49XXXXXXXX6@XXX.XX.XX.X6) State INIT going to sleep 2010-04-03 18:48:12.119630 [DEBUG] switch_core_state_machine.c:314 (sofia/external/49XXXXXXXX6@XXX.XX.XX.X6) Running State Change CS_ROUTING 2010-04-03 18:48:12.119630 [DEBUG] switch_core_state_machine.c:341 (sofia/external/49XXXXXXXX6@XXX.XX.XX.X6) State ROUTING 2010-04-03 18:48:12.119630 [DEBUG] mod_sofia.c:140 sofia/external/49XXXXXXXX6@XXX.XX.XX.X6 SOFIA ROUTING 2010-04-03 18:48:12.119630 [DEBUG] switch_core_state_machine.c:77 sofia/external/49XXXXXXXX6@XXX.XX.XX.X6 Standard ROUTING 2010-04-03 18:48:12.119630 [INFO] mod_dialplan_xml.c:418 Processing 49XXXXXXXX6->49331YYYYYY in context public Dialplan: sofia/external/49XXXXXXXX6@XXX.XX.XX.X6 parsing [public->unloop] continue=false Dialplan: sofia/external/49XXXXXXXX6@XXX.XX.XX.X6 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false Dialplan: sofia/external/49XXXXXXXX6@XXX.XX.XX.X6 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false Dialplan: sofia/external/49XXXXXXXX6@XXX.XX.XX.X6 parsing [public->outside_call] continue=true Dialplan: sofia/external/49XXXXXXXX6@XXX.XX.XX.X6 Absolute Condition [outside_call] Dialplan: sofia/external/49XXXXXXXX6@XXX.XX.XX.X6 Action set(outside_call=true) Dialplan: sofia/external/49XXXXXXXX6@XXX.XX.XX.X6 parsing [public->call_debug] continue=true Dialplan: sofia/external/49XXXXXXXX6@XXX.XX.XX.X6 Regex (FAIL) [call_debug] ${call_debug}(false) =~ /^true$/ break=never ... Dialplan: sofia/external/49XXXXXXXX6@XXX.XX.XX.X6 parsing [public->sns_conference] continue=false Dialplan: sofia/external/49XXXXXXXX6@XXX.XX.XX.X6 Regex (PASS) [sns_conference] destination_number(49331YYYYYY) =~ /^(49331YYYYYY)$/ break=on-false Dialplan: sofia/external/49XXXXXXXX6@XXX.XX.XX.X6 Action answer() Dialplan: sofia/external/49XXXXXXXX6@XXX.XX.XX.X6 Action set(conference_enforce_security=false) Dialplan: sofia/external/49XXXXXXXX6@XXX.XX.XX.X6 Action conference(49331YYYYYY-${domain_name}@default) 2010-04-03 18:48:12.119630 [DEBUG] switch_core_state_machine.c:119 (sofia/external/49XXXXXXXX6@XXX.XX.XX.X6) State Change CS_ROUTING -> CS_EXECUTE 2010-04-03 18:48:12.119630 [DEBUG] switch_core_session.c:1018 Send signal sofia/external/49XXXXXXXX6@XXX.XX.XX.X6 [BREAK] 2010-04-03 18:48:12.119630 [DEBUG] switch_core_state_machine.c:341 (sofia/external/49XXXXXXXX6@XXX.XX.XX.X6) State ROUTING going to sleep 2010-04-03 18:48:12.119630 [DEBUG] switch_core_state_machine.c:314 (sofia/external/49XXXXXXXX6@XXX.XX.XX.X6) Running State Change CS_EXECUTE 2010-04-03 18:48:12.119630 [DEBUG] switch_core_state_machine.c:348 (sofia/external/49XXXXXXXX6@XXX.XX.XX.X6) State EXECUTE 2010-04-03 18:48:12.119630 [DEBUG] mod_sofia.c:226 sofia/external/49XXXXXXXX6@XXX.XX.XX.X6 SOFIA EXECUTE 2010-04-03 18:48:12.119630 [DEBUG] switch_core_state_machine.c:157 sofia/external/49XXXXXXXX6@XXX.XX.XX.X6 Standard EXECUTE EXECUTE sofia/external/49XXXXXXXX6@XXX.XX.XX.X6 set(outside_call=true) 2010-04-03 18:48:12.119630 [DEBUG] mod_dptools.c:816 sofia/external/49XXXXXXXX6@XXX.XX.XX.X6 SET [outside_call]=[true] EXECUTE sofia/external/49XXXXXXXX6@XXX.XX.XX.X6 answer() 2010-04-03 18:48:12.119630 [DEBUG] sofia_glue.c:2594 AUDIO RTP [sofia/external/49XXXXXXXX6@XXX.XX.XX.X6] XXX.XX.XX.X7 port 21986 -> XXX.XX.XX.X6 port 22284 codec: 8 ms: 20 2010-04-03 18:48:12.119630 [DEBUG] switch_rtp.c:1182 Starting timer [soft] 160 bytes per 20ms 2010-04-03 18:48:12.122631 [DEBUG] sofia_glue.c:2774 Set 2833 dtmf send payload to 101 2010-04-03 18:48:12.122631 [DEBUG] sofia_glue.c:2779 Set 2833 dtmf receive payload to 101 2010-04-03 18:48:12.122631 [DEBUG] mod_sofia.c:636 Local SDP sofia/external/49XXXXXXXX6@XXX.XX.XX.X6: v=0 o=FreeSWITCH 1270291306 1270291307 IN IP4 XXX.XX.XX.X7 s=FreeSWITCH c=IN IP4 XXX.XX.XX.X7 t=0 0 m=audio 21986 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 2010-04-03 18:48:12.122631 [DEBUG] switch_core_session.c:638 Send signal sofia/external/49XXXXXXXX6@XXX.XX.XX.X6 [BREAK] 2010-04-03 18:48:12.122631 [NOTICE] mod_dptools.c:719 Channel [sofia/external/49XXXXXXXX6@XXX.XX.XX.X6] has been answered send 1068 bytes to udp/[XXX.XX.XX.X6]:5060 at 16:48:12.124920: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP XXX.XX.XX.X6:5060;branch=z9hG4bK-34ff11004c71ff10ff000024ff0c6dff From: <sip:49XXXXXXXX6@XXX.XX.XX.X6;user=phone>;tag=44ff11004c71ff10ff000024ff0c6dff To: <sip:49331YYYYYY@XXX.XX.XX.X7:5060;user=phone>;tag=Uv2Z4H5vNFyej Call-ID: 9aef11004c71b710800000248c0c6d9e@DOMAIN_ORIG CSeq: 1 INVITE Contact: <sip:49331YYYYYY@XXX.XX.XX.X7:5060;transport=udp> User-Agent: FreeSWITCH-mod_sofia/1.0.git-exportiert Accept: application/sdp Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY Supported: timer, precondition, path, replaces Allow-Events: talk, refer Content-Type: application/sdp Content-Disposition: session Content-Length: 247 Remote-Party-ID: "49331YYYYYY" <sip:49331YYYYYY@XXX.XX.XX.X7>;party=calling;privacy=off;screen=no v=0 EXECUTE sofia/external/49XXXXXXXX6@XXX.XX.XX.X6 set(conference_enforce_security=false) o=FreeSWITCH 1270291306 1270291307 IN IP4 XXX.XX.XX.X7 s=FreeSWITCH c=IN IP4 XXX.XX.XX.X7 t=0 0 m=audio 21986 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 ------------------------------------------------------------------------ 2010-04-03 18:48:12.122631 [DEBUG] sofia.c:4153 Channel sofia/external/49XXXXXXXX6@XXX.XX.XX.X6 entering state [completed][200] 2010-04-03 18:48:12.122631 [DEBUG] mod_dptools.c:816 sofia/external/49XXXXXXXX6@XXX.XX.XX.X6 SET [conference_enforce_security]=[false] EXECUTE sofia/external/49XXXXXXXX6@XXX.XX.XX.X6 conference(49331YYYYYY-XXX.XX.XX.X7@default) 2010-04-03 18:48:12.122631 [DEBUG] mod_conference.c:5671 Installing default caller control action 'mute' bound to '0'. 2010-04-03 18:48:12.122631 [DEBUG] switch_ivr.c:1616 max len 1 2010-04-03 18:48:12.122631 [DEBUG] switch_ivr.c:1620 min len 1 2010-04-03 18:48:12.122631 [DEBUG] mod_conference.c:5671 Installing default caller control action 'deaf mute' bound to '*'. 2010-04-03 18:48:12.122631 [DEBUG] mod_conference.c:5671 Installing default caller control action 'energy up' bound to '9'. 2010-04-03 18:48:12.122631 [DEBUG] mod_conference.c:5671 Installing default caller control action 'energy equ' bound to '8'. 2010-04-03 18:48:12.122631 [DEBUG] mod_conference.c:5671 Installing default caller control action 'energy dn' bound to '7'. 2010-04-03 18:48:12.122631 [DEBUG] mod_conference.c:5671 Installing default caller control action 'vol talk up' bound to '3'. 2010-04-03 18:48:12.122631 [DEBUG] mod_conference.c:5671 Installing default caller control action 'vol talk zero' bound to '2'. 2010-04-03 18:48:12.122631 [DEBUG] mod_conference.c:5671 Installing default caller control action 'vol talk dn' bound to '1'. 2010-04-03 18:48:12.122631 [DEBUG] mod_conference.c:5671 Installing default caller control action 'vol listen up' bound to '6'. 2010-04-03 18:48:12.122631 [DEBUG] mod_conference.c:5671 Installing default caller control action 'vol listen zero' bound to '5'. 2010-04-03 18:48:12.122631 [DEBUG] mod_conference.c:5671 Installing default caller control action 'vol listen dn' bound to '4'. 2010-04-03 18:48:12.122631 [DEBUG] mod_conference.c:5671 Installing default caller control action 'hangup' bound to '#'. 2010-04-03 18:48:12.122631 [DEBUG] mod_conference.c:4990 Raw Codec Activation Success L16@8000hz 1 channel 20ms 2010-04-03 18:48:12.122631 [DEBUG] mod_conference.c:5035 Raw Codec Activation Success L16@8000hz 1 channel 20ms 2010-04-03 18:48:12.125638 [DEBUG] switch_core_codec.c:122 sofia/external/49XXXXXXXX6@XXX.XX.XX.X6 Push codec L16:10 2010-04-03 18:48:12.125638 [DEBUG] switch_core_session.c:638 Send signal sofia/external/49XXXXXXXX6@XXX.XX.XX.X6 [BREAK] 2010-04-03 18:48:12.125638 [DEBUG] mod_conference.c:2202 Setup timer soft success interval: 20 samples: 160 2010-04-03 18:48:12.125638 [DEBUG] mod_conference.c:994 Setup timer success interval: 20 samples: 160 recv 445 bytes from udp/[XXX.XX.XX.X6]:5060 at 16:48:12.144223: ------------------------------------------------------------------------ ACK sip:49331YYYYYY@XXX.XX.XX.X7:5060;transport=udp SIP/2.0 Via: SIP/2.0/UDP XXX.XX.XX.X6:5060;branch=z9hG4bK-34ff11004c71ff10ff000024ff0c6dff From: <sip:49XXXXXXXX6@XXX.XX.XX.X6;user=phone>;tag=44ff11004c71ff10ff000024ff0c6dff To: <sip:49331YYYYYY@XXX.XX.XX.X7:5060;user=phone>;tag=Uv2Z4H5vNFyej Call-ID: 9aef11004c71b710800000248c0c6d9e@DOMAIN_ORIG CSeq: 1 ACK Max-Forwards: 10 User-Agent: MERA MSIP v.1.0.2 Content-Length: 0 ------------------------------------------------------------------------ 2010-04-03 18:48:12.143633 [DEBUG] sofia.c:4153 Channel sofia/external/49XXXXXXXX6@XXX.XX.XX.X6 entering state [ready][200] recv 446 bytes from udp/[XXX.XX.XX.X6]:5060 at 16:48:12.311539: ------------------------------------------------------------------------ BYE sip:49331YYYYYY@XXX.XX.XX.X7:5060;transport=udp SIP/2.0 Via: SIP/2.0/UDP XXX.XX.XX.X6:5060 From: <sip:49XXXXXXXX6@XXX.XX.XX.X6;user=phone>;tag=44ff11004c71ff10ff000024ff0c6dff To: <sip:49331YYYYYY@XXX.XX.XX.X7:5060;user=phone>;tag=Uv2Z4H5vNFyej Call-ID: 9aef11004c71b710800000248c0c6d9e@DOMAIN_ORIG CSeq: 2 BYE Max-Forwards: 10 User-Agent: MERA MSIP v.1.0.2 Reason: Q.850;cause=29;text="Facility rejected" Content-Length: 0 ------------------------------------------------------------------------ 2010-04-03 18:48:12.308666 [NOTICE] sofia.c:481 Hangup sofia/external/49XXXXXXXX6@XXX.XX.XX.X6 [CS_EXECUTE] [FACILITY_REJECTED] 2010-04-03 18:48:12.308666 [DEBUG] switch_channel.c:2071 Send signal sofia/external/49XXXXXXXX6@XXX.XX.XX.X6 [KILL] 2010-04-03 18:48:12.308666 [DEBUG] switch_core_session.c:1018 Send signal sofia/external/49XXXXXXXX6@XXX.XX.XX.X6 [BREAK] send 493 bytes to udp/[XXX.XX.XX.X6]:5060 at 16:48:12.311906: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP XXX.XX.XX.X6:5060 From: <sip:49XXXXXXXX6@XXX.XX.XX.X6;user=phone>;tag=44ff11004c71ff10ff000024ff0c6dff To: <sip:49331YYYYYY@XXX.XX.XX.X7:5060;user=phone>;tag=Uv2Z4H5vNFyej Call-ID: 9aef11004c71b710800000248c0c6d9e@DOMAIN_ORIG CSeq: 2 BYE User-Agent: FreeSWITCH-mod_sofia/1.0.git-exportiert Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY Supported: timer, precondition, path, replaces Content-Length: 0 ------------------------------------------------------------------------ 2010-04-03 18:48:12.326660 [DEBUG] mod_conference.c:2473 Channel leaving conference, cause: FACILITY_REJECTED 2010-04-03 18:48:12.329664 [DEBUG] switch_core_codec.c:146 sofia/external/49XXXXXXXX6@XXX.XX.XX.X6 Restore previous codec PCMA:8. 2010-04-03 18:48:12.329664 [DEBUG] switch_core_state_machine.c:348 (sofia/external/49XXXXXXXX6@XXX.XX.XX.X6) State EXECUTE going to sleep 2010-04-03 18:48:12.329664 [DEBUG] switch_core_state_machine.c:314 (sofia/external/49XXXXXXXX6@XXX.XX.XX.X6) Running State Change CS_HANGUP 2010-04-03 18:48:12.329664 [DEBUG] switch_core_state_machine.c:499 (sofia/external/49XXXXXXXX6@XXX.XX.XX.X6) State HANGUP 2010-04-03 18:48:12.329664 [DEBUG] mod_sofia.c:408 sofia/external/49XXXXXXXX6@XXX.XX.XX.X6 Overriding SIP cause 501 with 200 from the other leg 2010-04-03 18:48:12.329664 [DEBUG] mod_sofia.c:414 Channel sofia/external/49XXXXXXXX6@XXX.XX.XX.X6 hanging up, cause: FACILITY_REJECTED 2010-04-03 18:48:12.329664 [DEBUG] switch_core_state_machine.c:46 sofia/external/49XXXXXXXX6@XXX.XX.XX.X6 Standard HANGUP, cause: FACILITY_REJECTED 2010-04-03 18:48:12.329664 [DEBUG] switch_core_state_machine.c:499 (sofia/external/49XXXXXXXX6@XXX.XX.XX.X6) State HANGUP going to sleep 2010-04-03 18:48:12.329664 [DEBUG] switch_core_state_machine.c:333 (sofia/external/49XXXXXXXX6@XXX.XX.XX.X6) State Change CS_HANGUP -> CS_REPORTING 2010-04-03 18:48:12.329664 [DEBUG] switch_core_session.c:1018 Send signal sofia/external/49XXXXXXXX6@XXX.XX.XX.X6 [BREAK] 2010-04-03 18:48:12.329664 [DEBUG] switch_core_state_machine.c:314 (sofia/external/49XXXXXXXX6@XXX.XX.XX.X6) Running State Change CS_REPORTING 2010-04-03 18:48:12.329664 [DEBUG] switch_core_state_machine.c:590 (sofia/external/49XXXXXXXX6@XXX.XX.XX.X6) State REPORTING 2010-04-03 18:48:12.329664 [DEBUG] switch_core_state_machine.c:53 sofia/external/49XXXXXXXX6@XXX.XX.XX.X6 Standard REPORTING, cause: FACILITY_REJECTED 2010-04-03 18:48:12.329664 [DEBUG] switch_core_state_machine.c:590 (sofia/external/49XXXXXXXX6@XXX.XX.XX.X6) State REPORTING going to sleep 2010-04-03 18:48:12.329664 [DEBUG] switch_core_state_machine.c:327 (sofia/external/49XXXXXXXX6@XXX.XX.XX.X6) State Change CS_REPORTING -> CS_DESTROY 2010-04-03 18:48:12.329664 [DEBUG] switch_core_session.c:1018 Send signal sofia/external/49XXXXXXXX6@XXX.XX.XX.X6 [BREAK] 2010-04-03 18:48:12.329664 [DEBUG] switch_core_session.c:1161 Session 8 (sofia/external/49XXXXXXXX6@XXX.XX.XX.X6) Locked, Waiting on external entities 2010-04-03 18:48:12.329664 [NOTICE] switch_core_session.c:1179 Session 8 (sofia/external/49XXXXXXXX6@XXX.XX.XX.X6) Ended 2010-04-03 18:48:12.329664 [NOTICE] switch_core_session.c:1181 Close Channel sofia/external/49XXXXXXXX6@XXX.XX.XX.X6 [CS_DESTROY] 2010-04-03 18:48:12.329664 [DEBUG] switch_core_state_machine.c:428 (sofia/external/49XXXXXXXX6@XXX.XX.XX.X6) Running State Change CS_DESTROY 2010-04-03 18:48:12.329664 [DEBUG] switch_core_state_machine.c:439 (sofia/external/49XXXXXXXX6@XXX.XX.XX.X6) State DESTROY 2010-04-03 18:48:12.329664 [DEBUG] mod_sofia.c:341 sofia/external/49XXXXXXXX6@XXX.XX.XX.X6 SOFIA DESTROY 2010-04-03 18:48:12.329664 [DEBUG] switch_core_state_machine.c:60 sofia/external/49XXXXXXXX6@XXX.XX.XX.X6 Standard DESTROY 2010-04-03 18:48:12.329664 [DEBUG] switch_core_state_machine.c:439 (sofia/external/49XXXXXXXX6@XXX.XX.XX.X6) State DESTROY going to sleep 2010-04-03 18:48:12.347672 [DEBUG] mod_conference.c:1361 Write Lock ON 2010-04-03 18:48:12.347672 [DEBUG] mod_conference.c:1364 Write Lock OFF Brian West schrieb:
sofia profile xxxxx siptrace on (replace xxx with the profile)
Then try again.
/b
On Apr 3, 2010, at 10:14 AM, Jan Riedinger wrote:
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?
_______________________________________________ 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
-- 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
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
On Sat, Apr 03, Jan Riedinger 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?
Make sure you answer the channel first. But with out full logging we don't know what's going on. Do as BKW says. --FC
participants (4)
-
Anthony Minessale
-
Brian West
-
Frank Carmickle
-
Jan Riedinger