On Fri, Oct 16, 2009 at 9:18 AM, Anthony Minessale < anthony.minessale@gmail.com> wrote:

1) you should update again there were a few issues. 2) you can set the variable sip_callee_id_name and sip_callee_id number on the inbound leg before you answer to control what it says.

Thanks for the heads up. I added these two vars to the wiki on the chan vars page, SIP-related vars section. -MC

Hi Helmut, Just to add my 2 cents to the discussion, I have the same behaviour there... Regards, Gled. Helmut Kuper a écrit :

Hello Anthony,

I updated and restarted my test FS to "FreeSWITCH Version 1.0.trunk (15174M)". Callee's experience didn't change:

...

1. Phone rings: caller's displayname 2. Callee picks up: switching from dislayname to unknown 3. Switching from unknown to displayname

I used the two chvars you mentioned, set them via "set" and as well via "export" but no change (neither on caller's nor in callee's display nor in SIP INFO messages)

My dialplan portion for this is: <extension name="Local_Extension"> <condition field="${ET_is_local}" expression="^true$"> <action application="set" data="dialed_extension=${destination_number}"/> <action application="set" data="sip_callee_id_number=1111"/> <action application="set" data="sip_callee_id_name=hubu"/> <action application="export" data="sip_callee_id_number=1111"/> <action application="export" data="sip_callee_id_name=hubu"/> <action application="info"/> [...]

Here is the output of the info app after setting those chvars:

[INFO] mod_dptools.c:961 CHANNEL_DATA: Channel-State: [CS_EXECUTE] Channel-State-Number: [4] Channel-Name: [sofia/internal/1001@85.16.246.12:5061] Unique-ID: [4b143750-bcbd-11de-9f91-c9cd82739033] Call-Direction: [inbound] Presence-Call-Direction: [inbound] Answer-State: [ringing] Caller-Username: [1001] Caller-Dialplan: [XML] Caller-Caller-ID-Name: [1001 an PBX1] Caller-Caller-ID-Number: [1001] Caller-Network-Addr: [85.16.245.206] Caller-Destination-Number: [1000] Caller-Unique-ID: [4b143750-bcbd-11de-9f91-c9cd82739033] Caller-Source: [mod_sofia] Caller-Context: [default] Caller-RDNIS: [1000] Caller-Channel-Name: [sofia/internal/1001@85.16.246.12:5061] Caller-Profile-Index: [2] Caller-Profile-Created-Time: [1255963206242587] Caller-Channel-Created-Time: [1255963206214959] Caller-Channel-Answered-Time: [0] Caller-Channel-Progress-Time: [0] Caller-Channel-Progress-Media-Time: [0] Caller-Channel-Hangup-Time: [0] Caller-Channel-Transfer-Time: [0] Caller-Screen-Bit: [true] Caller-Privacy-Hide-Name: [false] Caller-Privacy-Hide-Number: [false] variable_sip_received_ip: [85.16.245.206] variable_sip_received_port: [1024] variable_sip_via_protocol: [udp] variable_sip_authorized: [true] variable_sip_from_user: [1001] variable_sip_from_port: [5061] variable_sip_from_uri: [1001@85.16.246.12:5061] variable_sip_from_host: [85.16.246.12] variable_sip_from_user_stripped: [1001] variable_sip_from_tag: [snfuiue6ga] variable_sofia_profile_name: [internal] variable_sip_req_params: [user=phone] variable_sip_req_user: [1000] variable_sip_req_port: [5061] variable_sip_req_uri: [1000@85.16.246.12:5061] variable_sip_req_host: [85.16.246.12] variable_sip_to_params: [user=phone] variable_sip_to_user: [1000] variable_sip_to_port: [5061] variable_sip_to_uri: [1000@85.16.246.12:5061] variable_sip_to_host: [85.16.246.12] variable_sip_contact_params: [line=eg3wp69a] variable_sip_contact_user: [1001] variable_sip_contact_port: [1024] variable_sip_contact_uri: [1001@85.16.245.206:1024] variable_sip_contact_host: [85.16.245.206] variable_channel_name: [sofia/internal/1001@85.16.246.12:5061] variable_sip_call_id: [3c2d2d8f9a49-edzr2i2iezjp] variable_sip_user_agent: [snom820/8.2.16] variable_sip_via_host: [85.16.245.206] variable_sip_via_port: [1024] variable_sip_via_rport: [1024] variable_presence_id: [1001@85.16.246.12] variable_sip_h_X-Serialnumber: [0004134002CB] variable_sip_h_P-Key-Flags: [resolution="31x13", keys="4"] variable_switch_r_sdp: [v=0 o=root 1331667919 1331667919 IN IP4 85.16.245.206 s=call c=IN IP4 85.16.245.206 t=0 0 m=audio 62882 RTP/SAVP 0 8 9 99 3 18 4 101 a=rtpmap:0 pcmu/8000 a=rtpmap:8 pcma/8000 a=rtpmap:9 g722/8000 a=rtpmap:99 g726-32/8000 a=rtpmap:3 gsm/8000 a=rtpmap:18 g729/8000 a=rtpmap:4 g723/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=crypto:1 AES_CM_128_HMAC_SHA1_32 inline:m6fas/KsLF57r9RnU7X0WEWeJw9Y6+a66YUIf9Dc a=ptime:20 m=audio 62882 RTP/AVP 0 8 9 99 3 18 4 101 a=rtpmap:0 pcmu/8000 a=rtpmap:8 pcma/8000 a=rtpmap:9 g722/8000 a=rtpmap:99 g726-32/8000 a=rtpmap:3 gsm/8000 a=rtpmap:18 g729/8000 a=rtpmap:4 g723/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 ] variable_ep_codec_string: [G722@8000h@20i,PCMA@8000h@20i] variable_endpoint_disposition: [DELAYED NEGOTIATION] variable_ET_is_local: [true] variable_max_forwards: [69] variable_domain_name: [85.16.246.12] variable_dialed_extension: [1000] variable_sip_callee_id_number: [1111] variable_sip_callee_id_name: [hubu] variable_export_vars: [sip_callee_id_number,sip_callee_id_name] variable_current_application: [info]

On 16.10.2009 18:18, Anthony Minessale wrote:

...

1) you should update again there were a few issues. 2) you can set the variable sip_callee_id_name and sip_callee_id number on the inbound leg before you answer to control what it says.

regards Helmut

_______________________________________________ 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

-----BEGIN PGP SIGNED MESSAGE----- Hash: SHA1 well, when I add "answer" before I bridge, there is a small change: There is no INFO message with "unknown" send to callee. The caller's display isn't affected by "sip_callee_*" chvars. On 19.10.2009 17:00, Anthony Minessale wrote:

you only need to "set" it on the inbound leg and you must answer and bridge it somewhere.

My current dialplan is this: <extension name="Local_Extension"> <condition field="${ET_is_local}" expression="^true$"> <action application="set" data="dialed_extension=${destination_number}"/> <action application="answer"/> <action application="set" data="sip_callee_id_number=1111"/> <action application="set" data="sip_callee_id_name=hubu"/> <action application="export" data="sip_callee_id_number=1111"/> <action application="export" data="sip_callee_id_name=hubu"/> <action application="info"/> <action application="export" data="dialed_extension=${destination_number}"/> <action application="set" data="transfer_ringback=$${hold_music}"/> <action application="set" data="hangup_after_bridge=true"/> <action application="export" data="nolocal:sip_secure_media=${user_data(${dialed_extension}@${domain_name} var sip_secure_media)}"/> <action application="db" data="insert/${domain_name}-last_dial/${called_party_callgroup}/${uuid}"/> <action application="set" data="continue_on_fail=false"/> <action application="bridge" data="user/${dialed_extension}@$${domain}"/> </condition> </extension> Here is the complete debug log: 2009-10-19 17:27:20.559060 [DEBUG] sofia.c:4906 IP 85.16.245.206 Approved by acl "clients[]". Access Granted. 2009-10-19 17:27:20.560087 [NOTICE] switch_channel.c:613 New Channel sofia/internal/1001@85.16.246.12:5061 [e47af3a6-bcc3-11de-9f91-c9cd82739033] 2009-10-19 17:27:20.560087 [DEBUG] sofia.c:3492 Channel sofia/internal/1001@85.16.246.12:5061 entering state [received][100] 2009-10-19 17:27:20.560087 [DEBUG] sofia.c:3499 Remote SDP: v=0 o=root 903333206 903333206 IN IP4 85.16.245.206 s=call c=IN IP4 85.16.245.206 t=0 0 m=audio 54598 RTP/SAVP 0 8 9 99 3 18 4 101 a=rtpmap:0 pcmu/8000 a=rtpmap:8 pcma/8000 a=rtpmap:9 g722/8000 a=rtpmap:99 g726-32/8000 a=rtpmap:3 gsm/8000 a=rtpmap:18 g729/8000 a=rtpmap:4 g723/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=crypto:1 AES_CM_128_HMAC_SHA1_32 inline:zS0VqvwW7z/dnmeWvtZS0AwhJ1ru1J1tuHJO2JRD a=ptime:20 m=audio 54598 RTP/AVP 0 8 9 99 3 18 4 101 a=rtpmap:0 pcmu/8000 a=rtpmap:8 pcma/8000 a=rtpmap:9 g722/8000 a=rtpmap:99 g726-32/8000 a=rtpmap:3 gsm/8000 a=rtpmap:18 g729/8000 a=rtpmap:4 g723/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 2009-10-19 17:27:20.560087 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/1001@85.16.246.12:5061) Running State Change CS_NEW 2009-10-19 17:27:20.560087 [DEBUG] switch_core_state_machine.c:312 (sofia/internal/1001@85.16.246.12:5061) State NEW 2009-10-19 17:27:20.560087 [DEBUG] sofia.c:3620 (sofia/internal/1001@85.16.246.12:5061) State Change CS_NEW -> CS_INIT 2009-10-19 17:27:20.560087 [DEBUG] switch_core_session.c:969 Send signal sofia/internal/1001@85.16.246.12:5061 [BREAK] 2009-10-19 17:27:20.560087 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/1001@85.16.246.12:5061) Running State Change CS_INIT 2009-10-19 17:27:20.560087 [DEBUG] switch_core_state_machine.c:330 (sofia/internal/1001@85.16.246.12:5061) State INIT 2009-10-19 17:27:20.560087 [DEBUG] mod_sofia.c:83 sofia/internal/1001@85.16.246.12:5061 SOFIA INIT 2009-10-19 17:27:20.560087 [DEBUG] mod_sofia.c:111 (sofia/internal/1001@85.16.246.12:5061) State Change CS_INIT -> CS_ROUTING 2009-10-19 17:27:20.560087 [DEBUG] switch_core_session.c:969 Send signal sofia/internal/1001@85.16.246.12:5061 [BREAK] 2009-10-19 17:27:20.560087 [DEBUG] switch_core_state_machine.c:330 (sofia/internal/1001@85.16.246.12:5061) State INIT going to sleep 2009-10-19 17:27:20.560087 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/1001@85.16.246.12:5061) Running State Change CS_ROUTING 2009-10-19 17:27:20.560087 [DEBUG] switch_core_state_machine.c:333 (sofia/internal/1001@85.16.246.12:5061) State ROUTING 2009-10-19 17:27:20.560087 [DEBUG] mod_sofia.c:130 sofia/internal/1001@85.16.246.12:5061 SOFIA ROUTING 2009-10-19 17:27:20.560087 [DEBUG] switch_core_state_machine.c:78 sofia/internal/1001@85.16.246.12:5061 Standard ROUTING 2009-10-19 17:27:20.560087 [INFO] mod_dialplan_xml.c:391 Processing 1001 an PBX1->1000 in context default Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->anonymous] continue=true Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [anonymous] destination_number(1000) =~ /^\*31([0-9]+)$/ break=never Dialplan: sofia/internal/1001@85.16.246.12:5061 ANTI-Action set(dialed_extension=${destination_number}) Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->is_local] continue=true Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [is_local] ${ET_is_local}() =~ /(true|false)/ break=on-true Dialplan: sofia/internal/1001@85.16.246.12:5061 ANTI-Action lua(ET_is_local.lua) Dialplan: sofia/internal/1001@85.16.246.12:5061 ANTI-Action transfer(${destination_number} XML default) Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->set_domain] continue=true Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (PASS) [set_domain] destination_number(1000) =~ /^.*$/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 Action set(domain_name=85.16.246.12) Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->302_zero_problem] continue=true Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [302_zero_problem] ${sip_looped_call}() =~ /true/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->fakeTrisko-Servicenumber as callerid] continue=true Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [fakeTrisko-Servicenumber as callerid] caller_id_number(1001) =~ /^(49[1-2][0-9])$/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->outbound-ssw-ddi] continue=false Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [outbound-ssw-ddi] ${dialed_extension}() =~ /^0([0-9]+)$/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->mail] continue=false Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [mail] destination_number(1000) =~ /^9997$/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->pizza] continue=false Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [pizza] destination_number(1000) =~ /^2006$/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->pbook] continue=false Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [pbook] destination_number(1000) =~ /^2007$/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->shout_1] continue=false Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [shout_1] ${dialed_extension}() =~ /^9123$/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->shout_2] continue=false Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [shout_2] ${dialed_extension}() =~ /^9124$/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->speex_32000] continue=false Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [speex_32000] ${dialed_extension}() =~ /^9125$/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->speex_32000_2] continue=false Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [speex_32000_2] ${dialed_extension}() =~ /^9126$/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->jukebox] continue=false Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [jukebox] destination_number(1000) =~ /^9111$/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->pickup-extension] continue=false Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [pickup-extension] ${dialed_extension}() =~ /^\*\*(\d+)$/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->Voicemail-Verwaltung] continue=false Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [Voicemail-Verwaltung] ${dialed_extension}() =~ /^(1001|9999)$/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->Local_Extension] continue=false Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [Local_Extension] ${ET_is_local}() =~ /^true$/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->parallel call] continue=false Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [parallel call] ${dialed_extension}() =~ /^(6000)$/ break=on-false 2009-10-19 17:27:20.562064 [DEBUG] switch_core_state_machine.c:114 (sofia/internal/1001@85.16.246.12:5061) State Change CS_ROUTING -> CS_EXECUTE 2009-10-19 17:27:20.562064 [DEBUG] switch_core_session.c:969 Send signal sofia/internal/1001@85.16.246.12:5061 [BREAK] 2009-10-19 17:27:20.562064 [DEBUG] switch_core_state_machine.c:333 (sofia/internal/1001@85.16.246.12:5061) State ROUTING going to sleep 2009-10-19 17:27:20.563061 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/1001@85.16.246.12:5061) Running State Change CS_EXECUTE 2009-10-19 17:27:20.563061 [DEBUG] switch_core_state_machine.c:340 (sofia/internal/1001@85.16.246.12:5061) State EXECUTE 2009-10-19 17:27:20.563061 [DEBUG] mod_sofia.c:173 sofia/internal/1001@85.16.246.12:5061 SOFIA EXECUTE 2009-10-19 17:27:20.563061 [DEBUG] switch_core_state_machine.c:151 sofia/internal/1001@85.16.246.12:5061 Standard EXECUTE EXECUTE sofia/internal/1001@85.16.246.12:5061 set(dialed_extension=1000) 2009-10-19 17:27:20.563061 [DEBUG] mod_dptools.c:752 sofia/internal/1001@85.16.246.12:5061 SET [dialed_extension]=[1000] EXECUTE sofia/internal/1001@85.16.246.12:5061 lua(ET_is_local.lua) 2009-10-19 17:27:20.588079 [DEBUG] switch_cpp.cpp:925 destroy/unlink session from object EXECUTE sofia/internal/1001@85.16.246.12:5061 transfer(1000 XML default) 2009-10-19 17:27:20.588079 [DEBUG] switch_ivr.c:1381 (sofia/internal/1001@85.16.246.12:5061) State Change CS_EXECUTE -> CS_ROUTING 2009-10-19 17:27:20.588079 [DEBUG] switch_core_session.c:969 Send signal sofia/internal/1001@85.16.246.12:5061 [BREAK] 2009-10-19 17:27:20.588079 [DEBUG] switch_ivr.c:1385 sofia/internal/1001@85.16.246.12:5061 receive message [TRANSFER] 2009-10-19 17:27:20.588079 [DEBUG] switch_core_session.c:639 Send signal sofia/internal/1001@85.16.246.12:5061 [BREAK] 2009-10-19 17:27:20.588079 [NOTICE] switch_ivr.c:1387 Transfer sofia/internal/1001@85.16.246.12:5061 to XML[1000@default] 2009-10-19 17:27:20.588079 [DEBUG] switch_core_state_machine.c:340 (sofia/internal/1001@85.16.246.12:5061) State EXECUTE going to sleep 2009-10-19 17:27:20.588079 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/1001@85.16.246.12:5061) Running State Change CS_ROUTING 2009-10-19 17:27:20.588079 [DEBUG] switch_core_state_machine.c:333 (sofia/internal/1001@85.16.246.12:5061) State ROUTING 2009-10-19 17:27:20.588079 [DEBUG] mod_sofia.c:130 sofia/internal/1001@85.16.246.12:5061 SOFIA ROUTING 2009-10-19 17:27:20.588079 [DEBUG] switch_core_state_machine.c:78 sofia/internal/1001@85.16.246.12:5061 Standard ROUTING 2009-10-19 17:27:20.588079 [INFO] mod_dialplan_xml.c:391 Processing 1001 an PBX1->1000 in context default Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->anonymous] continue=true Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [anonymous] destination_number(1000) =~ /^\*31([0-9]+)$/ break=never Dialplan: sofia/internal/1001@85.16.246.12:5061 ANTI-Action set(dialed_extension=${destination_number}) Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->is_local] continue=true Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (PASS) [is_local] ${ET_is_local}(true) =~ /(true|false)/ break=on-true Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->set_domain] continue=true Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (PASS) [set_domain] destination_number(1000) =~ /^.*$/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 Action set(domain_name=85.16.246.12) Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->302_zero_problem] continue=true Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [302_zero_problem] ${sip_looped_call}() =~ /true/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->fakeTrisko-Servicenumber as callerid] continue=true Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [fakeTrisko-Servicenumber as callerid] caller_id_number(1001) =~ /^(49[1-2][0-9])$/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->outbound-ssw-ddi] continue=false Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [outbound-ssw-ddi] ${dialed_extension}(1000) =~ /^0([0-9]+)$/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->mail] continue=false Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [mail] destination_number(1000) =~ /^9997$/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->pizza] continue=false Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [pizza] destination_number(1000) =~ /^2006$/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->pbook] continue=false Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [pbook] destination_number(1000) =~ /^2007$/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->shout_1] continue=false Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [shout_1] ${dialed_extension}(1000) =~ /^9123$/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->shout_2] continue=false Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [shout_2] ${dialed_extension}(1000) =~ /^9124$/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->speex_32000] continue=false Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [speex_32000] ${dialed_extension}(1000) =~ /^9125$/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->speex_32000_2] continue=false Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [speex_32000_2] ${dialed_extension}(1000) =~ /^9126$/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->jukebox] continue=false Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [jukebox] destination_number(1000) =~ /^9111$/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->pickup-extension] continue=false Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [pickup-extension] ${dialed_extension}(1000) =~ /^\*\*(\d+)$/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->Voicemail-Verwaltung] continue=false Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [Voicemail-Verwaltung] ${dialed_extension}(1000) =~ /^(1001|9999)$/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->Local_Extension] continue=false Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (PASS) [Local_Extension] ${ET_is_local}(true) =~ /^true$/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 Action set(dialed_extension=${destination_number}) Dialplan: sofia/internal/1001@85.16.246.12:5061 Action answer() Dialplan: sofia/internal/1001@85.16.246.12:5061 Action set(sip_callee_id_number=1111) Dialplan: sofia/internal/1001@85.16.246.12:5061 Action set(sip_callee_id_name=hubu) Dialplan: sofia/internal/1001@85.16.246.12:5061 Action export(sip_callee_id_number=1111) Dialplan: sofia/internal/1001@85.16.246.12:5061 Action export(sip_callee_id_name=hubu) Dialplan: sofia/internal/1001@85.16.246.12:5061 Action info() Dialplan: sofia/internal/1001@85.16.246.12:5061 Action export(dialed_extension=${destination_number}) Dialplan: sofia/internal/1001@85.16.246.12:5061 Action set(transfer_ringback=local_stream://moh) Dialplan: sofia/internal/1001@85.16.246.12:5061 Action set(hangup_after_bridge=true) Dialplan: sofia/internal/1001@85.16.246.12:5061 Action export(nolocal:sip_secure_media=${user_data(${dialed_extension}@${domain_name} var sip_secure_media)}) Dialplan: sofia/internal/1001@85.16.246.12:5061 Action db(insert/${domain_name}-last_dial/${called_party_callgroup}/${uuid}) Dialplan: sofia/internal/1001@85.16.246.12:5061 Action set(continue_on_fail=false) Dialplan: sofia/internal/1001@85.16.246.12:5061 Action bridge(user/${dialed_extension}@85.16.246.12) 2009-10-19 17:27:20.590066 [DEBUG] switch_core_state_machine.c:114 (sofia/internal/1001@85.16.246.12:5061) State Change CS_ROUTING -> CS_EXECUTE 2009-10-19 17:27:20.590066 [DEBUG] switch_core_session.c:969 Send signal sofia/internal/1001@85.16.246.12:5061 [BREAK] 2009-10-19 17:27:20.590066 [DEBUG] switch_core_state_machine.c:333 (sofia/internal/1001@85.16.246.12:5061) State ROUTING going to sleep 2009-10-19 17:27:20.590066 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/1001@85.16.246.12:5061) Running State Change CS_EXECUTE 2009-10-19 17:27:20.590066 [DEBUG] switch_core_state_machine.c:340 (sofia/internal/1001@85.16.246.12:5061) State EXECUTE 2009-10-19 17:27:20.590066 [DEBUG] mod_sofia.c:173 sofia/internal/1001@85.16.246.12:5061 SOFIA EXECUTE 2009-10-19 17:27:20.591072 [DEBUG] switch_core_state_machine.c:151 sofia/internal/1001@85.16.246.12:5061 Standard EXECUTE EXECUTE sofia/internal/1001@85.16.246.12:5061 set(dialed_extension=1000) 2009-10-19 17:27:20.591072 [DEBUG] mod_dptools.c:752 sofia/internal/1001@85.16.246.12:5061 SET [dialed_extension]=[1000] EXECUTE sofia/internal/1001@85.16.246.12:5061 set(domain_name=85.16.246.12) 2009-10-19 17:27:20.591072 [DEBUG] mod_dptools.c:752 sofia/internal/1001@85.16.246.12:5061 SET [domain_name]=[85.16.246.12] EXECUTE sofia/internal/1001@85.16.246.12:5061 set(dialed_extension=1000) 2009-10-19 17:27:20.591072 [DEBUG] mod_dptools.c:752 sofia/internal/1001@85.16.246.12:5061 SET [dialed_extension]=[1000] EXECUTE sofia/internal/1001@85.16.246.12:5061 answer() 2009-10-19 17:27:20.591072 [DEBUG] mod_dptools.c:653 sofia/internal/1001@85.16.246.12:5061 receive message [ANSWER] 2009-10-19 17:27:20.591072 [DEBUG] sofia_glue.c:3028 Set Remote Key [1 AES_CM_128_HMAC_SHA1_32 inline:zS0VqvwW7z/dnmeWvtZS0AwhJ1ru1J1tuHJO2JRD] 2009-10-19 17:27:20.591072 [DEBUG] sofia_glue.c:2160 Set Local Key [1 AES_CM_128_HMAC_SHA1_32 inline:GteACgi6L6LMWBxdEnW4/XSOUhsmp/iKk9dZjmBJ] 2009-10-19 17:27:20.591072 [DEBUG] sofia_glue.c:3142 Audio Codec Compare [pcmu:0:8000:20]/[CELT:95:48000:10] 2009-10-19 17:27:20.591072 [DEBUG] sofia_glue.c:3142 Audio Codec Compare [pcmu:0:8000:20]/[SPEEX:103:32000:20] 2009-10-19 17:27:20.591072 [DEBUG] sofia_glue.c:3142 Audio Codec Compare [pcmu:0:8000:20]/[G722:9:8000:20] 2009-10-19 17:27:20.591072 [DEBUG] sofia_glue.c:3142 Audio Codec Compare [pcmu:0:8000:20]/[PCMA:8:8000:20] 2009-10-19 17:27:20.591072 [DEBUG] sofia_glue.c:3142 Audio Codec Compare [pcma:8:8000:20]/[CELT:95:48000:10] 2009-10-19 17:27:20.591072 [DEBUG] sofia_glue.c:3142 Audio Codec Compare [pcma:8:8000:20]/[SPEEX:103:32000:20] 2009-10-19 17:27:20.591072 [DEBUG] sofia_glue.c:3142 Audio Codec Compare [pcma:8:8000:20]/[G722:9:8000:20] 2009-10-19 17:27:20.591072 [DEBUG] sofia_glue.c:3142 Audio Codec Compare [pcma:8:8000:20]/[PCMA:8:8000:20] 2009-10-19 17:27:20.591072 [DEBUG] sofia_glue.c:2100 Set Codec sofia/internal/1001@85.16.246.12:5061 PCMA/8000 20 ms 160 samples 2009-10-19 17:27:20.591072 [DEBUG] sofia_glue.c:3102 Set 2833 dtmf payload to 101 2009-10-19 17:27:20.591072 [DEBUG] sofia_glue.c:3076 Our existing sdp is still good [pcma 85.16.245.206:54598], let's keep it. 2009-10-19 17:27:20.591072 [DEBUG] sofia_glue.c:2334 AUDIO RTP [sofia/internal/1001@85.16.246.12:5061] 85.16.246.12 port 14580 -> 85.16.245.206 port 54598 codec: 8 ms: 20 2009-10-19 17:27:20.591072 [DEBUG] switch_rtp.c:1155 Starting timer [soft] 160 bytes per 20ms 2009-10-19 17:27:20.594059 [INFO] switch_rtp.c:1019 Activating Secure RTP SEND 2009-10-19 17:27:20.594059 [INFO] switch_rtp.c:999 Activating Secure RTP RECV 2009-10-19 17:27:20.594059 [DEBUG] switch_core_sqldb.c:396 Secure Type: srtp:AES_CM_128_HMAC_SHA1_32 2009-10-19 17:27:20.594059 [DEBUG] switch_core_sqldb.c:396 Secure Type: srtp:AES_CM_128_HMAC_SHA1_32 2009-10-19 17:27:20.594059 [NOTICE] sofia_glue.c:2769 Pre-Answer sofia/internal/1001@85.16.246.12:5061! 2009-10-19 17:27:20.594059 [DEBUG] mod_sofia.c:543 Local SDP sofia/internal/1001@85.16.246.12:5061: v=0 o=FreeSWITCH 1255951460 1255951461 IN IP4 85.16.246.12 s=FreeSWITCH c=IN IP4 85.16.246.12 t=0 0 m=audio 14580 RTP/SAVP 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 a=crypto:1 AES_CM_128_HMAC_SHA1_32 inline:GteACgi6L6LMWBxdEnW4/XSOUhsmp/iKk9dZjmBJ 2009-10-19 17:27:20.594059 [DEBUG] switch_core_session.c:639 Send signal sofia/internal/1001@85.16.246.12:5061 [BREAK] 2009-10-19 17:27:20.594059 [NOTICE] mod_dptools.c:653 Channel [sofia/internal/1001@85.16.246.12:5061] has been answered 2009-10-19 17:27:20.594059 [DEBUG] switch_channel.c:182 sofia/internal/1001@85.16.246.12:5061 receive message [AUDIO_SYNC] EXECUTE sofia/internal/1001@85.16.246.12:5061 set(sip_callee_id_number=1111) 2009-10-19 17:27:20.594059 [DEBUG] mod_dptools.c:752 sofia/internal/1001@85.16.246.12:5061 SET [sip_callee_id_number]=[1111] 2009-10-19 17:27:20.595060 [DEBUG] sofia.c:3492 Channel sofia/internal/1001@85.16.246.12:5061 entering state [completed][200] EXECUTE sofia/internal/1001@85.16.246.12:5061 set(sip_callee_id_name=hubu) 2009-10-19 17:27:20.595060 [DEBUG] mod_dptools.c:752 sofia/internal/1001@85.16.246.12:5061 SET [sip_callee_id_name]=[hubu] EXECUTE sofia/internal/1001@85.16.246.12:5061 export(sip_callee_id_number=1111) 2009-10-19 17:27:20.595060 [DEBUG] mod_dptools.c:896 EXPORT [sip_callee_id_number]=[1111] EXECUTE sofia/internal/1001@85.16.246.12:5061 export(sip_callee_id_name=hubu) 2009-10-19 17:27:20.595060 [DEBUG] mod_dptools.c:896 EXPORT [sip_callee_id_name]=[hubu] EXECUTE sofia/internal/1001@85.16.246.12:5061 info() 2009-10-19 17:27:20.596059 [INFO] mod_dptools.c:961 CHANNEL_DATA: Channel-State: [CS_EXECUTE] Channel-State-Number: [4] Channel-Name: [sofia/internal/1001@85.16.246.12:5061] Unique-ID: [e47af3a6-bcc3-11de-9f91-c9cd82739033] Call-Direction: [inbound] Presence-Call-Direction: [inbound] Answer-State: [answered] Channel-Read-Codec-Name: [PCMA] Channel-Read-Codec-Rate: [8000] Channel-Write-Codec-Name: [PCMA] Channel-Write-Codec-Rate: [8000] Caller-Username: [1001] Caller-Dialplan: [XML] Caller-Caller-ID-Name: [1001 an PBX1] Caller-Caller-ID-Number: [1001] Caller-Network-Addr: [85.16.245.206] Caller-Destination-Number: [1000] Caller-Unique-ID: [e47af3a6-bcc3-11de-9f91-c9cd82739033] Caller-Source: [mod_sofia] Caller-Context: [default] Caller-RDNIS: [1000] Caller-Channel-Name: [sofia/internal/1001@85.16.246.12:5061] Caller-Profile-Index: [2] Caller-Profile-Created-Time: [1255966040588079] Caller-Channel-Created-Time: [1255966040560087] Caller-Channel-Answered-Time: [1255966040594059] Caller-Channel-Progress-Time: [0] Caller-Channel-Progress-Media-Time: [1255966040594059] Caller-Channel-Hangup-Time: [0] Caller-Channel-Transfer-Time: [0] Caller-Screen-Bit: [true] Caller-Privacy-Hide-Name: [false] Caller-Privacy-Hide-Number: [false] variable_sip_received_ip: [85.16.245.206] variable_sip_received_port: [1024] variable_sip_via_protocol: [udp] variable_sip_authorized: [true] variable_sip_from_user: [1001] variable_sip_from_port: [5061] variable_sip_from_uri: [1001@85.16.246.12:5061] variable_sip_from_host: [85.16.246.12] variable_sip_from_user_stripped: [1001] variable_sip_from_tag: [02p9o5jbg8] variable_sofia_profile_name: [internal] variable_sip_req_params: [user=phone] variable_sip_req_user: [1000] variable_sip_req_port: [5061] variable_sip_req_uri: [1000@85.16.246.12:5061] variable_sip_req_host: [85.16.246.12] variable_sip_to_params: [user=phone] variable_sip_to_user: [1000] variable_sip_to_port: [5061] variable_sip_to_uri: [1000@85.16.246.12:5061] variable_sip_to_host: [85.16.246.12] variable_sip_contact_params: [line=eg3wp69a] variable_sip_contact_user: [1001] variable_sip_contact_port: [1024] variable_sip_contact_uri: [1001@85.16.245.206:1024] variable_sip_contact_host: [85.16.245.206] variable_channel_name: [sofia/internal/1001@85.16.246.12:5061] variable_sip_call_id: [3c2d38a1cd82-iphmvbhl46hj] variable_sip_user_agent: [snom820/8.2.16] variable_sip_via_host: [85.16.245.206] variable_sip_via_port: [1024] variable_sip_via_rport: [1024] variable_presence_id: [1001@85.16.246.12] variable_sip_h_X-Serialnumber: [0004134002CB] variable_sip_h_P-Key-Flags: [resolution="31x13", keys="4"] variable_switch_r_sdp: [v=0 o=root 903333206 903333206 IN IP4 85.16.245.206 s=call c=IN IP4 85.16.245.206 t=0 0 m=audio 54598 RTP/SAVP 0 8 9 99 3 18 4 101 a=rtpmap:0 pcmu/8000 a=rtpmap:8 pcma/8000 a=rtpmap:9 g722/8000 a=rtpmap:99 g726-32/8000 a=rtpmap:3 gsm/8000 a=rtpmap:18 g729/8000 a=rtpmap:4 g723/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=crypto:1 AES_CM_128_HMAC_SHA1_32 inline:zS0VqvwW7z/dnmeWvtZS0AwhJ1ru1J1tuHJO2JRD a=ptime:20 m=audio 54598 RTP/AVP 0 8 9 99 3 18 4 101 a=rtpmap:0 pcmu/8000 a=rtpmap:8 pcma/8000 a=rtpmap:9 g722/8000 a=rtpmap:99 g726-32/8000 a=rtpmap:3 gsm/8000 a=rtpmap:18 g729/8000 a=rtpmap:4 g723/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 ] variable_ep_codec_string: [G722@8000h@20i,PCMA@8000h@20i] variable_ET_is_local: [true] variable_max_forwards: [69] variable_domain_name: [85.16.246.12] variable_dialed_extension: [1000] variable_sip_has_crypto: [AES_CM_128_HMAC_SHA1_32] variable_sip_crypto_mandatory: [true] variable_sip_secure_media: [true] variable_remote_media_ip: [85.16.245.206] variable_remote_media_port: [54598] variable_read_codec: [PCMA] variable_read_rate: [8000] variable_write_codec: [PCMA] variable_write_rate: [8000] variable_local_media_ip: [85.16.246.12] variable_local_media_port: [14580] variable_sip_secure_media_confirmed: [true] variable_endpoint_disposition: [ANSWER] variable_sip_callee_id_number: [1111] variable_sip_callee_id_name: [hubu] variable_export_vars: [sip_callee_id_number,sip_callee_id_name] variable_current_application: [info] EXECUTE sofia/internal/1001@85.16.246.12:5061 export(dialed_extension=1000) 2009-10-19 17:27:20.596059 [DEBUG] mod_dptools.c:896 EXPORT [dialed_extension]=[1000] EXECUTE sofia/internal/1001@85.16.246.12:5061 set(transfer_ringback=local_stream://moh) 2009-10-19 17:27:20.596059 [DEBUG] mod_dptools.c:752 sofia/internal/1001@85.16.246.12:5061 SET [transfer_ringback]=[local_stream://moh] EXECUTE sofia/internal/1001@85.16.246.12:5061 set(hangup_after_bridge=true) 2009-10-19 17:27:20.597073 [DEBUG] mod_dptools.c:752 sofia/internal/1001@85.16.246.12:5061 SET [hangup_after_bridge]=[true] EXECUTE sofia/internal/1001@85.16.246.12:5061 export(nolocal:sip_secure_media=) 2009-10-19 17:27:20.621072 [DEBUG] mod_dptools.c:896 EXPORT (REMOTE ONLY) [sip_secure_media]=[UNDEF] EXECUTE sofia/internal/1001@85.16.246.12:5061 db(insert/85.16.246.12-last_dial//e47af3a6-bcc3-11de-9f91-c9cd82739033) 2009-10-19 17:27:20.832283 [DEBUG] sofia.c:3492 Channel sofia/internal/1001@85.16.246.12:5061 entering state [ready][200] EXECUTE sofia/internal/1001@85.16.246.12:5061 set(continue_on_fail=false) 2009-10-19 17:27:20.841267 [DEBUG] mod_dptools.c:752 sofia/internal/1001@85.16.246.12:5061 SET [continue_on_fail]=[false] EXECUTE sofia/internal/1001@85.16.246.12:5061 bridge(user/1000@85.16.246.12) 2009-10-19 17:27:20.865064 [DEBUG] switch_ivr_originate.c:1162 variable string 0 = [presence_id=1000@85.16.246.12] 2009-10-19 17:27:20.865064 [NOTICE] switch_channel.c:613 New Channel sofia/internal/sip:1000@85.16.245.228:1024 [e4a9a7a0-bcc3-11de-9f91-c9cd82739033] 2009-10-19 17:27:20.866297 [DEBUG] mod_sofia.c:2981 (sofia/internal/sip:1000@85.16.245.228:1024) State Change CS_NEW -> CS_INIT 2009-10-19 17:27:20.866297 [DEBUG] switch_core_session.c:969 Send signal sofia/internal/sip:1000@85.16.245.228:1024 [BREAK] 2009-10-19 17:27:20.866297 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/sip:1000@85.16.245.228:1024) Running State Change CS_INIT 2009-10-19 17:27:20.866297 [DEBUG] switch_core_state_machine.c:330 (sofia/internal/sip:1000@85.16.245.228:1024) State INIT 2009-10-19 17:27:20.866297 [DEBUG] mod_sofia.c:83 sofia/internal/sip:1000@85.16.245.228:1024 SOFIA INIT 2009-10-19 17:27:20.866297 [DEBUG] mod_sofia.c:111 (sofia/internal/sip:1000@85.16.245.228:1024) State Change CS_INIT -> CS_ROUTING 2009-10-19 17:27:20.866297 [DEBUG] switch_core_session.c:969 Send signal sofia/internal/sip:1000@85.16.245.228:1024 [BREAK] 2009-10-19 17:27:20.866297 [DEBUG] switch_core_state_machine.c:330 (sofia/internal/sip:1000@85.16.245.228:1024) State INIT going to sleep 2009-10-19 17:27:20.866297 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/sip:1000@85.16.245.228:1024) Running State Change CS_ROUTING 2009-10-19 17:27:20.866297 [DEBUG] switch_core_state_machine.c:333 (sofia/internal/sip:1000@85.16.245.228:1024) State ROUTING 2009-10-19 17:27:20.866297 [DEBUG] mod_sofia.c:130 sofia/internal/sip:1000@85.16.245.228:1024 SOFIA ROUTING 2009-10-19 17:27:20.866297 [DEBUG] switch_ivr_originate.c:66 (sofia/internal/sip:1000@85.16.245.228:1024) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2009-10-19 17:27:20.866297 [DEBUG] switch_core_session.c:969 Send signal sofia/internal/sip:1000@85.16.245.228:1024 [BREAK] 2009-10-19 17:27:20.866297 [DEBUG] switch_core_state_machine.c:333 (sofia/internal/sip:1000@85.16.245.228:1024) State ROUTING going to sleep 2009-10-19 17:27:20.866297 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/sip:1000@85.16.245.228:1024) Running State Change CS_CONSUME_MEDIA 2009-10-19 17:27:20.866297 [DEBUG] switch_ivr_originate.c:1880 Raw Codec Activation Success L16@8000hz 1 channel 20ms 2009-10-19 17:27:20.866297 [DEBUG] switch_ivr_originate.c:1912 Play Ringback File [local_stream://moh] 2009-10-19 17:27:20.866297 [DEBUG] mod_local_stream.c:408 Opening Stream [moh/8000] 8000hz 2009-10-19 17:27:20.866297 [DEBUG] switch_core_state_machine.c:352 (sofia/internal/sip:1000@85.16.245.228:1024) State CONSUME_MEDIA 2009-10-19 17:27:20.866297 [DEBUG] switch_core_state_machine.c:352 (sofia/internal/sip:1000@85.16.245.228:1024) State CONSUME_MEDIA going to sleep 2009-10-19 17:27:20.868064 [DEBUG] sofia.c:3492 Channel sofia/internal/sip:1000@85.16.245.228:1024 entering state [calling][0] 2009-10-19 17:27:20.877064 [DEBUG] switch_core_io.c:652 sofia/internal/1001@85.16.246.12:5061 receive message [TRANSCODING_NECESSARY] 2009-10-19 17:27:20.887288 [DEBUG] sofia.c:3492 Channel sofia/internal/sip:1000@85.16.245.228:1024 entering state [proceeding][180] 2009-10-19 17:27:20.887288 [NOTICE] sofia.c:3556 Ring-Ready sofia/internal/sip:1000@85.16.245.228:1024! 2009-10-19 17:27:21.392073 [DEBUG] sofia.c:3492 Channel sofia/internal/sip:1000@85.16.245.228:1024 entering state [proceeding][180] 2009-10-19 17:27:22.403086 [DEBUG] sofia.c:3492 Channel sofia/internal/sip:1000@85.16.245.228:1024 entering state [proceeding][180] 2009-10-19 17:27:22.849092 [DEBUG] sofia.c:3492 Channel sofia/internal/sip:1000@85.16.245.228:1024 entering state [ready][200] 2009-10-19 17:27:22.849092 [DEBUG] sofia.c:3499 Remote SDP: v=0 o=root 1600007648 1600007649 IN IP4 85.16.245.228 s=call c=IN IP4 85.16.245.228 t=0 0 m=audio 50468 RTP/AVP 8 9 101 a=rtpmap:8 pcma/8000 a=rtpmap:9 g722/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 2009-10-19 17:27:22.849092 [DEBUG] sofia_glue.c:3142 Audio Codec Compare [pcma:8:8000:20]/[PCMA:8:8000:20] 2009-10-19 17:27:22.849092 [DEBUG] sofia_glue.c:2100 Set Codec sofia/internal/sip:1000@85.16.245.228:1024 PCMA/8000 20 ms 160 samples 2009-10-19 17:27:22.849092 [DEBUG] sofia_glue.c:3102 Set 2833 dtmf payload to 101 2009-10-19 17:27:22.849092 [DEBUG] sofia_glue.c:2334 AUDIO RTP [sofia/internal/sip:1000@85.16.245.228:1024] 85.16.246.12 port 16276 -> 85.16.245.228 port 50468 codec: 8 ms: 20 2009-10-19 17:27:22.850096 [DEBUG] switch_rtp.c:1155 Starting timer [soft] 160 bytes per 20ms 2009-10-19 17:27:22.851093 [DEBUG] switch_channel.c:2046 Send signal sofia/internal/1001@85.16.246.12:5061 [BREAK] 2009-10-19 17:27:22.851093 [NOTICE] sofia.c:3982 Channel [sofia/internal/sip:1000@85.16.245.228:1024] has been answered 2009-10-19 17:27:22.851093 [DEBUG] switch_channel.c:182 sofia/internal/sip:1000@85.16.245.228:1024 receive message [AUDIO_SYNC] 2009-10-19 17:27:22.857294 [DEBUG] switch_core_codec.c:122 Restore original codec. 2009-10-19 17:27:22.857294 [DEBUG] switch_ivr_originate.c:2363 Originate Resulted in Success: [sofia/internal/sip:1000@85.16.245.228:1024] 2009-10-19 17:27:22.857294 [DEBUG] switch_channel.c:182 sofia/internal/sip:1000@85.16.245.228:1024 receive message [AUDIO_SYNC] 2009-10-19 17:27:22.857294 [DEBUG] switch_channel.c:182 sofia/internal/1001@85.16.246.12:5061 receive message [AUDIO_SYNC] 2009-10-19 17:27:22.857294 [DEBUG] switch_ivr_originate.c:1880 Raw Codec Activation Success L16@8000hz 1 channel 20ms 2009-10-19 17:27:22.858097 [DEBUG] switch_ivr_originate.c:1912 Play Ringback File [local_stream://moh] 2009-10-19 17:27:22.858097 [DEBUG] mod_local_stream.c:408 Opening Stream [moh/8000] 8000hz 2009-10-19 17:27:22.858097 [DEBUG] switch_core_codec.c:122 Restore original codec. 2009-10-19 17:27:22.858097 [DEBUG] switch_ivr_originate.c:2363 Originate Resulted in Success: [sofia/internal/sip:1000@85.16.245.228:1024] 2009-10-19 17:27:22.858097 [DEBUG] switch_channel.c:182 sofia/internal/sip:1000@85.16.245.228:1024 receive message [AUDIO_SYNC] 2009-10-19 17:27:22.858097 [DEBUG] switch_channel.c:182 sofia/internal/1001@85.16.246.12:5061 receive message [AUDIO_SYNC] 2009-10-19 17:27:22.858097 [DEBUG] switch_ivr_bridge.c:966 sofia/internal/sip:1000@85.16.245.228:1024 receive message [BRIDGE] 2009-10-19 17:27:22.858097 [DEBUG] switch_core_session.c:639 Send signal sofia/internal/sip:1000@85.16.245.228:1024 [BREAK] 2009-10-19 17:27:22.858097 [DEBUG] switch_ivr_bridge.c:973 sofia/internal/1001@85.16.246.12:5061 receive message [BRIDGE] 2009-10-19 17:27:22.858097 [DEBUG] switch_core_session.c:639 Send signal sofia/internal/1001@85.16.246.12:5061 [BREAK] 2009-10-19 17:27:22.858097 [DEBUG] switch_ivr_bridge.c:1017 (sofia/internal/sip:1000@85.16.245.228:1024) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA 2009-10-19 17:27:22.858097 [DEBUG] switch_core_session.c:969 Send signal sofia/internal/sip:1000@85.16.245.228:1024 [BREAK] 2009-10-19 17:27:22.858097 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/sip:1000@85.16.245.228:1024) Running State Change CS_EXCHANGE_MEDIA 2009-10-19 17:27:22.858097 [DEBUG] switch_core_state_machine.c:343 (sofia/internal/sip:1000@85.16.245.228:1024) State EXCHANGE_MEDIA 2009-10-19 17:27:22.858097 [DEBUG] mod_sofia.c:436 SOFIA LOOPBACK 2009-10-19 17:27:22.877303 [DEBUG] switch_ivr_bridge.c:115 sofia/internal/sip:1000@85.16.245.228:1024 receive message [DISPLAY] 2009-10-19 17:27:22.877303 [DEBUG] switch_ivr_bridge.c:115 sofia/internal/1001@85.16.246.12:5061 receive message [DISPLAY] 2009-10-19 17:27:24.568124 [NOTICE] sofia.c:328 Hangup sofia/internal/sip:1000@85.16.245.228:1024 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING] 2009-10-19 17:27:24.568124 [DEBUG] switch_channel.c:1837 Send signal sofia/internal/sip:1000@85.16.245.228:1024 [KILL] 2009-10-19 17:27:24.568124 [DEBUG] switch_core_session.c:969 Send signal sofia/internal/sip:1000@85.16.245.228:1024 [BREAK] 2009-10-19 17:27:24.568124 [DEBUG] switch_core_state_machine.c:449 thread mismatch skipping state handler. 2009-10-19 17:27:24.577124 [DEBUG] switch_ivr_bridge.c:446 sofia/internal/sip:1000@85.16.245.228:1024 ending bridge by request from read function 2009-10-19 17:27:24.577124 [DEBUG] switch_ivr_bridge.c:521 BRIDGE THREAD DONE [sofia/internal/sip:1000@85.16.245.228:1024] 2009-10-19 17:27:24.577124 [DEBUG] switch_ivr_bridge.c:523 Send signal sofia/internal/1001@85.16.246.12:5061 [BREAK] 2009-10-19 17:27:24.577124 [DEBUG] switch_core_state_machine.c:343 (sofia/internal/sip:1000@85.16.245.228:1024) State EXCHANGE_MEDIA going to sleep 2009-10-19 17:27:24.577124 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/sip:1000@85.16.245.228:1024) Running State Change CS_HANGUP 2009-10-19 17:27:24.577124 [DEBUG] switch_ivr_bridge.c:440 sofia/internal/sip:1000@85.16.245.228:1024 ending bridge by request from write function 2009-10-19 17:27:24.577124 [DEBUG] switch_ivr_bridge.c:495 sofia/internal/1001@85.16.246.12:5061 receive message [UNBRIDGE] 2009-10-19 17:27:24.577124 [DEBUG] switch_core_session.c:639 Send signal sofia/internal/1001@85.16.246.12:5061 [BREAK] 2009-10-19 17:27:24.577124 [DEBUG] switch_ivr_bridge.c:521 BRIDGE THREAD DONE [sofia/internal/1001@85.16.246.12:5061] 2009-10-19 17:27:24.577124 [DEBUG] switch_ivr_bridge.c:523 Send signal sofia/internal/sip:1000@85.16.245.228:1024 [BREAK] 2009-10-19 17:27:24.577124 [NOTICE] switch_ivr_bridge.c:1093 Hangup sofia/internal/1001@85.16.246.12:5061 [CS_EXECUTE] [NORMAL_CLEARING] 2009-10-19 17:27:24.577124 [DEBUG] switch_channel.c:1837 Send signal sofia/internal/1001@85.16.246.12:5061 [KILL] 2009-10-19 17:27:24.577124 [DEBUG] switch_core_session.c:969 Send signal sofia/internal/1001@85.16.246.12:5061 [BREAK] 2009-10-19 17:27:24.577124 [DEBUG] switch_core_state_machine.c:476 (sofia/internal/sip:1000@85.16.245.228:1024) State HANGUP 2009-10-19 17:27:24.577124 [DEBUG] mod_sofia.c:344 Channel sofia/internal/sip:1000@85.16.245.228:1024 hanging up, cause: NORMAL_CLEARING 2009-10-19 17:27:24.577124 [DEBUG] switch_core_state_machine.c:46 sofia/internal/sip:1000@85.16.245.228:1024 Standard HANGUP, cause: NORMAL_CLEARING 2009-10-19 17:27:24.577124 [DEBUG] switch_core_state_machine.c:476 (sofia/internal/sip:1000@85.16.245.228:1024) State HANGUP going to sleep 2009-10-19 17:27:24.578354 [DEBUG] switch_core_state_machine.c:325 (sofia/internal/sip:1000@85.16.245.228:1024) State Change CS_HANGUP -> CS_REPORTING 2009-10-19 17:27:24.578354 [DEBUG] switch_core_session.c:969 Send signal sofia/internal/sip:1000@85.16.245.228:1024 [BREAK] 2009-10-19 17:27:24.578354 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/sip:1000@85.16.245.228:1024) Running State Change CS_REPORTING 2009-10-19 17:27:24.578354 [DEBUG] switch_core_state_machine.c:567 (sofia/internal/sip:1000@85.16.245.228:1024) State REPORTING 2009-10-19 17:27:24.578354 [DEBUG] switch_core_state_machine.c:53 sofia/internal/sip:1000@85.16.245.228:1024 Standard REPORTING, cause: NORMAL_CLEARING 2009-10-19 17:27:24.578354 [DEBUG] switch_core_state_machine.c:567 (sofia/internal/sip:1000@85.16.245.228:1024) State REPORTING going to sleep 2009-10-19 17:27:24.578354 [DEBUG] switch_core_state_machine.c:319 (sofia/internal/sip:1000@85.16.245.228:1024) State Change CS_REPORTING - -> CS_DESTROY 2009-10-19 17:27:24.578354 [DEBUG] switch_core_session.c:969 Send signal sofia/internal/sip:1000@85.16.245.228:1024 [BREAK] 2009-10-19 17:27:24.578354 [DEBUG] switch_core_state_machine.c:476 (sofia/internal/1001@85.16.246.12:5061) State HANGUP 2009-10-19 17:27:24.578354 [DEBUG] mod_sofia.c:344 Channel sofia/internal/1001@85.16.246.12:5061 hanging up, cause: NORMAL_CLEARING 2009-10-19 17:27:24.578354 [DEBUG] switch_core_session.c:1106 Session 46 (sofia/internal/sip:1000@85.16.245.228:1024) Locked, Waiting on external entities 2009-10-19 17:27:24.578354 [DEBUG] mod_sofia.c:382 Sending BYE to sofia/internal/1001@85.16.246.12:5061 2009-10-19 17:27:24.579127 [DEBUG] switch_core_state_machine.c:46 sofia/internal/1001@85.16.246.12:5061 Standard HANGUP, cause: NORMAL_CLEARING 2009-10-19 17:27:24.579127 [DEBUG] switch_core_state_machine.c:476 (sofia/internal/1001@85.16.246.12:5061) State HANGUP going to sleep 2009-10-19 17:27:24.579127 [NOTICE] switch_core_session.c:1124 Session 46 (sofia/internal/sip:1000@85.16.245.228:1024) Ended 2009-10-19 17:27:24.579127 [NOTICE] switch_core_session.c:1126 Close Channel sofia/internal/sip:1000@85.16.245.228:1024 [CS_DESTROY] 2009-10-19 17:27:24.579127 [DEBUG] switch_core_state_machine.c:413 (sofia/internal/sip:1000@85.16.245.228:1024) Running State Change CS_DESTROY 2009-10-19 17:27:24.579127 [DEBUG] switch_core_state_machine.c:340 (sofia/internal/1001@85.16.246.12:5061) State EXECUTE going to sleep 2009-10-19 17:27:24.579127 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/1001@85.16.246.12:5061) Running State Change CS_HANGUP 2009-10-19 17:27:24.579127 [DEBUG] switch_core_state_machine.c:424 (sofia/internal/sip:1000@85.16.245.228:1024) State DESTROY 2009-10-19 17:27:24.579127 [DEBUG] mod_sofia.c:261 sofia/internal/sip:1000@85.16.245.228:1024 SOFIA DESTROY 2009-10-19 17:27:24.579127 [DEBUG] switch_core_state_machine.c:60 sofia/internal/sip:1000@85.16.245.228:1024 Standard DESTROY 2009-10-19 17:27:24.579127 [DEBUG] switch_core_state_machine.c:454 handler already called, skipping state handler. 2009-10-19 17:27:24.579127 [DEBUG] switch_core_state_machine.c:424 (sofia/internal/sip:1000@85.16.245.228:1024) State DESTROY going to sleep 2009-10-19 17:27:24.579127 [DEBUG] switch_core_state_machine.c:325 (sofia/internal/1001@85.16.246.12:5061) State Change CS_HANGUP -> CS_REPORTING 2009-10-19 17:27:24.579127 [DEBUG] switch_core_session.c:969 Send signal sofia/internal/1001@85.16.246.12:5061 [BREAK] 2009-10-19 17:27:24.579127 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/1001@85.16.246.12:5061) Running State Change CS_REPORTING 2009-10-19 17:27:24.579127 [DEBUG] switch_core_state_machine.c:567 (sofia/internal/1001@85.16.246.12:5061) State REPORTING 2009-10-19 17:27:24.581127 [DEBUG] switch_core_state_machine.c:53 sofia/internal/1001@85.16.246.12:5061 Standard REPORTING, cause: NORMAL_CLEARING 2009-10-19 17:27:24.581127 [DEBUG] switch_core_state_machine.c:567 (sofia/internal/1001@85.16.246.12:5061) State REPORTING going to sleep 2009-10-19 17:27:24.581127 [DEBUG] switch_core_state_machine.c:319 (sofia/internal/1001@85.16.246.12:5061) State Change CS_REPORTING -> CS_DESTROY 2009-10-19 17:27:24.581127 [DEBUG] switch_core_session.c:969 Send signal sofia/internal/1001@85.16.246.12:5061 [BREAK] 2009-10-19 17:27:24.581127 [DEBUG] switch_core_session.c:1106 Session 45 (sofia/internal/1001@85.16.246.12:5061) Locked, Waiting on external entities 2009-10-19 17:27:24.581127 [NOTICE] switch_core_session.c:1124 Session 45 (sofia/internal/1001@85.16.246.12:5061) Ended 2009-10-19 17:27:24.581127 [NOTICE] switch_core_session.c:1126 Close Channel sofia/internal/1001@85.16.246.12:5061 [CS_DESTROY] 2009-10-19 17:27:24.581127 [DEBUG] switch_core_state_machine.c:413 (sofia/internal/1001@85.16.246.12:5061) Running State Change CS_DESTROY 2009-10-19 17:27:24.581127 [DEBUG] switch_core_state_machine.c:424 (sofia/internal/1001@85.16.246.12:5061) State DESTROY 2009-10-19 17:27:24.581127 [DEBUG] mod_sofia.c:261 sofia/internal/1001@85.16.246.12:5061 SOFIA DESTROY 2009-10-19 17:27:24.581127 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1001@85.16.246.12:5061 Standard DESTROY 2009-10-19 17:27:24.581127 [DEBUG] switch_core_state_machine.c:424 (sofia/internal/1001@85.16.246.12:5061) State DESTROY going to sleep -----BEGIN PGP SIGNATURE----- Version: GnuPG v1.4.7 (MingW32) iD8DBQFK3IW/4tZeNddg3dwRAhrgAJ49KN/qc6Mqol2HC8JOVw6CxgU3MQCePJY7 or8ESeZIzc4ZkMqaUJLxzUQ= =P2wM -----END PGP SIGNATURE-----

please update and test trunk 1) I changed the core to remove the excess data by default in your scenario 2) I added variables you can use to control it origination_callee_id_name origination_callee_id_number which belong in {} in the dial string eg {origination_callee_id_number=1234}openzap/1/a/1234 On Mon, Oct 19, 2009 at 10:53 AM, Helmut Kuper <helmut.kuper@ewetel.de>wrote:

-----BEGIN PGP SIGNED MESSAGE----- Hash: SHA1

Hi Anthony,

just to make it clear: my goal is to avoid to see something (newly introduced since 1 or 2 weeks) like "1/a/890327" for outgoing in the caller's display after answering the call (for openzap calls). I want simply e.g. "89327". I don't want to put the call into "answer" state before the called side demands it. So I'm not sure if sip_callee_* is the right way to follow as long as it needs the answer state.

regards helmut

-----BEGIN PGP SIGNATURE----- Version: GnuPG v1.4.7 (MingW32)

iD8DBQFK3Itu4tZeNddg3dwRAvREAKCrZcRM+TnypdVOczpe2+q26b3BYQCfadrY tdA2XIc/hpuIv/916eu4GFo= =y6Md -----END PGP SIGNATURE-----

_______________________________________________ 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> iax:guest@conference.freeswitch.org/888 googletalk:conf+888@conference.freeswitch.org<googletalk%3Aconf%2B888@conference.freeswitch.org> pstn:213-799-1400

-----BEGIN PGP SIGNED MESSAGE----- Hash: SHA1 Hello Mike, just updated my prod system. The "1/a/" problem is solved with Anthony's "originate_callee_id_name" chvar. thanks alot :) So, last thing of this thread is still the "unknown" thing on callee's display, which is (by now) NOT affected by the new chvars. regards Helmut On 19.10.2009 23:35, Michael Collins wrote:

On Mon, Oct 19, 2009 at 1:07 PM, Anthony Minessale <anthony.minessale@gmail.com <mailto:anthony.minessale@gmail.com>> wrote:

please update and test trunk

1) I changed the core to remove the excess data by default in your scenario 2) I added variables you can use to control it origination_callee_id_name origination_callee_id_number which belong in {} in the dial string eg {origination_callee_id_number=1234}openzap/1/a/1234

After you test, please confirm the behavior and then we'll update the wiki on these two new chan vars. -MC

-----BEGIN PGP SIGNATURE----- Version: GnuPG v1.4.7 (MingW32) iD8DBQFK30g74tZeNddg3dwRAjaGAKDDNnxPPg+lmlCSs33MCw/V191q3ACdFlpv Alf3NeoCA8Qbm2PZ1k2HHOg= =hzVn -----END PGP SIGNATURE-----

-----BEGIN PGP SIGNED MESSAGE----- Hash: SHA1 Hi Mike, here it is: Dialplan: <extension name="Local_Extension"> <condition field="destination_number" expression="^(10[01][0-9])$"> <action application="set" data="dialed_extension=$1"/> <action application="export" data="dialed_extension=$1"/> <action application="set" data="transfer_ringback=$${hold_music}"/> <action application="set" data="hangup_after_bridge=true"/> <action application="bridge" data="user/${dialed_extension}@${domain_name}"/> </condition> </extension> Debug-Log: recv 1521 bytes from udp/[85.16.245.206]:1024 at 15:41:02.405834: ------------------------------------------------------------------------ INVITE sip:1000@85.16.246.12:5061;user=phone SIP/2.0 Via: SIP/2.0/UDP 85.16.245.206:1024;branch=z9hG4bK-i48n75d62qts;rport From: "1001 an PBX1" <sip:1001@85.16.246.12:5061>;tag=7xpim4o1go To: <sip:1000@85.16.246.12:5061;user=phone> Call-ID: 3c31304b7a80-no9xsnjj0bol CSeq: 1 INVITE Max-Forwards: 70 Contact: <sip:1001@85.16.245.206:1024;line=eg3wp69a>;reg-id=1 X-Serialnumber: 0004134002CB P-Key-Flags: resolution="31x13", keys="4" User-Agent: snom820/8.2.16 Accept: application/sdp Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO, UPDATE Allow-Events: talk, hold, refer, call-info Supported: timer, 100rel, replaces, from-change Session-Expires: 3600;refresher=uas Min-SE: 90 Content-Type: application/sdp Content-Length: 732 v=0 o=root 411395140 411395140 IN IP4 85.16.245.206 s=call c=IN IP4 85.16.245.206 t=0 0 m=audio 49852 RTP/SAVP 0 8 9 99 3 18 4 101 a=crypto:1 AES_CM_128_HMAC_SHA1_32 inline:Um06txYC7vwXdZDohXJ15te5hZ/iWmPd4voRbdby a=rtpmap:0 pcmu/8000 a=rtpmap:8 pcma/8000 a=rtpmap:9 g722/8000 a=rtpmap:99 g726-32/8000 a=rtpmap:3 gsm/8000 a=rtpmap:18 g729/8000 a=rtpmap:4 g723/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv m=audio 49852 RTP/AVP 0 8 9 99 3 18 4 101 a=rtpmap:0 pcmu/8000 a=rtpmap:8 pcma/8000 a=rtpmap:9 g722/8000 a=rtpmap:99 g726-32/8000 a=rtpmap:3 gsm/8000 a=rtpmap:18 g729/8000 a=rtpmap:4 g723/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv ------------------------------------------------------------------------ send 331 bytes to udp/[85.16.245.206]:1024 at 15:41:02.406500: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 85.16.245.206:1024;branch=z9hG4bK-i48n75d62qts;rport=1024 From: "1001 an PBX1" <sip:1001@85.16.246.12:5061>;tag=7xpim4o1go To: <sip:1000@85.16.246.12:5061;user=phone> Call-ID: 3c31304b7a80-no9xsnjj0bol CSeq: 1 INVITE User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15180M Content-Length: 0 ------------------------------------------------------------------------ 2009-10-22 17:41:02.406509 [DEBUG] sofia.c:4907 IP 85.16.245.206 Approved by acl "clients[]". Access Granted. 2009-10-22 17:41:02.406509 [NOTICE] switch_channel.c:613 New Channel sofia/internal/1001@85.16.246.12:5061 [4d941750-bf21-11de-9c3f-adfc1789590a] 2009-10-22 17:41:02.407509 [DEBUG] sofia.c:3493 Channel sofia/internal/1001@85.16.246.12:5061 entering state [received][100] 2009-10-22 17:41:02.407509 [DEBUG] sofia.c:3500 Remote SDP: v=0 o=root 411395140 411395140 IN IP4 85.16.245.206 s=call c=IN IP4 85.16.245.206 t=0 0 m=audio 49852 RTP/SAVP 0 8 9 99 3 18 4 101 a=rtpmap:0 pcmu/8000 a=rtpmap:8 pcma/8000 a=rtpmap:9 g722/8000 a=rtpmap:99 g726-32/8000 a=rtpmap:3 gsm/8000 a=rtpmap:18 g729/8000 a=rtpmap:4 g723/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=crypto:1 AES_CM_128_HMAC_SHA1_32 inline:Um06txYC7vwXdZDohXJ15te5hZ/iWmPd4voRbdby a=ptime:20 m=audio 49852 RTP/AVP 0 8 9 99 3 18 4 101 a=rtpmap:0 pcmu/8000 a=rtpmap:8 pcma/8000 a=rtpmap:9 g722/8000 a=rtpmap:99 g726-32/8000 a=rtpmap:3 gsm/8000 a=rtpmap:18 g729/8000 a=rtpmap:4 g723/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 2009-10-22 17:41:02.407509 [DEBUG] sofia.c:3621 (sofia/internal/1001@85.16.246.12:5061) State Change CS_NEW -> CS_INIT 2009-10-22 17:41:02.407509 [DEBUG] switch_core_session.c:977 Send signal sofia/internal/1001@85.16.246.12:5061 [BREAK] 2009-10-22 17:41:02.407509 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/1001@85.16.246.12:5061) Running State Change CS_INIT 2009-10-22 17:41:02.407509 [DEBUG] switch_core_state_machine.c:330 (sofia/internal/1001@85.16.246.12:5061) State INIT 2009-10-22 17:41:02.407509 [DEBUG] mod_sofia.c:83 sofia/internal/1001@85.16.246.12:5061 SOFIA INIT 2009-10-22 17:41:02.407509 [DEBUG] mod_sofia.c:111 (sofia/internal/1001@85.16.246.12:5061) State Change CS_INIT -> CS_ROUTING 2009-10-22 17:41:02.407509 [DEBUG] switch_core_session.c:977 Send signal sofia/internal/1001@85.16.246.12:5061 [BREAK] 2009-10-22 17:41:02.407509 [DEBUG] switch_core_state_machine.c:330 (sofia/internal/1001@85.16.246.12:5061) State INIT going to sleep 2009-10-22 17:41:02.407509 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/1001@85.16.246.12:5061) Running State Change CS_ROUTING 2009-10-22 17:41:02.407509 [DEBUG] switch_core_state_machine.c:333 (sofia/internal/1001@85.16.246.12:5061) State ROUTING 2009-10-22 17:41:02.407509 [DEBUG] mod_sofia.c:130 sofia/internal/1001@85.16.246.12:5061 SOFIA ROUTING 2009-10-22 17:41:02.407509 [DEBUG] switch_core_state_machine.c:78 sofia/internal/1001@85.16.246.12:5061 Standard ROUTING 2009-10-22 17:41:02.407509 [INFO] mod_dialplan_xml.c:391 Processing 1001 an PBX1->1000 in context default Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->anonymous] continue=true Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [anonymous] destination_number(1000) =~ /^\*31([0-9]+)$/ break=never Dialplan: sofia/internal/1001@85.16.246.12:5061 ANTI-Action set(dialed_extension=${destination_number}) Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->is_local] continue=true Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [is_local] ${ET_is_local}() =~ /(true|false)/ break=on-true Dialplan: sofia/internal/1001@85.16.246.12:5061 ANTI-Action lua(ET_is_local.lua) Dialplan: sofia/internal/1001@85.16.246.12:5061 ANTI-Action transfer(${destination_number} XML default) Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->set_domain] continue=true Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (PASS) [set_domain] destination_number(1000) =~ /^.*$/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 Action set(domain_name=85.16.246.12) Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->302_zero_problem] continue=true Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [302_zero_problem] ${sip_looped_call}() =~ /true/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->fakeTrisko-Servicenumber as callerid] continue=true Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [fakeTrisko-Servicenumber as callerid] caller_id_number(1001) =~ /^(49[1-2][0-9])$/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->outbound-ssw-ddi] continue=false Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [outbound-ssw-ddi] ${dialed_extension}() =~ /^0([0-9]+)$/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->mail] continue=false Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [mail] destination_number(1000) =~ /^9997$/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->pizza] continue=false Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [pizza] destination_number(1000) =~ /^2006$/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->pbook] continue=false Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [pbook] destination_number(1000) =~ /^2007$/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->shout_1] continue=false Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [shout_1] ${dialed_extension}() =~ /^9123$/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->shout_2] continue=false Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [shout_2] ${dialed_extension}() =~ /^9124$/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->speex_32000] continue=false Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [speex_32000] ${dialed_extension}() =~ /^9125$/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->speex_32000_2] continue=false Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [speex_32000_2] ${dialed_extension}() =~ /^9126$/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->jukebox] continue=false Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [jukebox] destination_number(1000) =~ /^9111$/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->pickup-extension] continue=false Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [pickup-extension] ${dialed_extension}() =~ /^\*\*(\d+)$/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->Voicemail-Verwaltung] continue=false Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [Voicemail-Verwaltung] ${dialed_extension}() =~ /^(1001|9999)$/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->Local_Extension] continue=false Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [Local_Extension] ${ET_is_local}() =~ /^true$/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->parallel call] continue=false Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [parallel call] ${dialed_extension}() =~ /^(6000)$/ break=on-false 2009-10-22 17:41:02.409510 [DEBUG] switch_core_state_machine.c:114 (sofia/internal/1001@85.16.246.12:5061) State Change CS_ROUTING -> CS_EXECUTE 2009-10-22 17:41:02.409510 [DEBUG] switch_core_session.c:977 Send signal sofia/internal/1001@85.16.246.12:5061 [BREAK] 2009-10-22 17:41:02.409510 [DEBUG] switch_core_state_machine.c:333 (sofia/internal/1001@85.16.246.12:5061) State ROUTING going to sleep 2009-10-22 17:41:02.409510 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/1001@85.16.246.12:5061) Running State Change CS_EXECUTE 2009-10-22 17:41:02.409510 [DEBUG] switch_core_state_machine.c:340 (sofia/internal/1001@85.16.246.12:5061) State EXECUTE 2009-10-22 17:41:02.409510 [DEBUG] mod_sofia.c:173 sofia/internal/1001@85.16.246.12:5061 SOFIA EXECUTE 2009-10-22 17:41:02.409510 [DEBUG] switch_core_state_machine.c:151 sofia/internal/1001@85.16.246.12:5061 Standard EXECUTE EXECUTE sofia/internal/1001@85.16.246.12:5061 set(dialed_extension=1000) 2009-10-22 17:41:02.409510 [DEBUG] mod_dptools.c:752 sofia/internal/1001@85.16.246.12:5061 SET [dialed_extension]=[1000] EXECUTE sofia/internal/1001@85.16.246.12:5061 lua(ET_is_local.lua) 2009-10-22 17:41:02.434510 [DEBUG] switch_cpp.cpp:925 destroy/unlink session from object EXECUTE sofia/internal/1001@85.16.246.12:5061 transfer(1000 XML default) 2009-10-22 17:41:02.434510 [DEBUG] switch_ivr.c:1381 (sofia/internal/1001@85.16.246.12:5061) State Change CS_EXECUTE -> CS_ROUTING 2009-10-22 17:41:02.434510 [DEBUG] switch_core_session.c:977 Send signal sofia/internal/1001@85.16.246.12:5061 [BREAK] 2009-10-22 17:41:02.434510 [DEBUG] switch_ivr.c:1385 sofia/internal/1001@85.16.246.12:5061 receive message [TRANSFER] 2009-10-22 17:41:02.434510 [DEBUG] switch_core_session.c:647 Send signal sofia/internal/1001@85.16.246.12:5061 [BREAK] 2009-10-22 17:41:02.434510 [NOTICE] switch_ivr.c:1387 Transfer sofia/internal/1001@85.16.246.12:5061 to XML[1000@default] 2009-10-22 17:41:02.435510 [DEBUG] switch_core_state_machine.c:340 (sofia/internal/1001@85.16.246.12:5061) State EXECUTE going to sleep 2009-10-22 17:41:02.435510 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/1001@85.16.246.12:5061) Running State Change CS_ROUTING 2009-10-22 17:41:02.435510 [DEBUG] switch_core_state_machine.c:333 (sofia/internal/1001@85.16.246.12:5061) State ROUTING 2009-10-22 17:41:02.435510 [DEBUG] mod_sofia.c:130 sofia/internal/1001@85.16.246.12:5061 SOFIA ROUTING 2009-10-22 17:41:02.435510 [DEBUG] switch_core_state_machine.c:78 sofia/internal/1001@85.16.246.12:5061 Standard ROUTING 2009-10-22 17:41:02.435510 [INFO] mod_dialplan_xml.c:391 Processing 1001 an PBX1->1000 in context default Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->anonymous] continue=true Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [anonymous] destination_number(1000) =~ /^\*31([0-9]+)$/ break=never Dialplan: sofia/internal/1001@85.16.246.12:5061 ANTI-Action set(dialed_extension=${destination_number}) Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->is_local] continue=true Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (PASS) [is_local] ${ET_is_local}(true) =~ /(true|false)/ break=on-true Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->set_domain] continue=true Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (PASS) [set_domain] destination_number(1000) =~ /^.*$/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 Action set(domain_name=85.16.246.12) Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->302_zero_problem] continue=true Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [302_zero_problem] ${sip_looped_call}() =~ /true/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->fakeTrisko-Servicenumber as callerid] continue=true Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [fakeTrisko-Servicenumber as callerid] caller_id_number(1001) =~ /^(49[1-2][0-9])$/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->outbound-ssw-ddi] continue=false Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [outbound-ssw-ddi] ${dialed_extension}(1000) =~ /^0([0-9]+)$/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->mail] continue=false Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [mail] destination_number(1000) =~ /^9997$/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->pizza] continue=false Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [pizza] destination_number(1000) =~ /^2006$/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->pbook] continue=false Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [pbook] destination_number(1000) =~ /^2007$/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->shout_1] continue=false Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [shout_1] ${dialed_extension}(1000) =~ /^9123$/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->shout_2] continue=false Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [shout_2] ${dialed_extension}(1000) =~ /^9124$/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->speex_32000] continue=false Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [speex_32000] ${dialed_extension}(1000) =~ /^9125$/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->speex_32000_2] continue=false Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [speex_32000_2] ${dialed_extension}(1000) =~ /^9126$/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->jukebox] continue=false Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [jukebox] destination_number(1000) =~ /^9111$/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->pickup-extension] continue=false Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [pickup-extension] ${dialed_extension}(1000) =~ /^\*\*(\d+)$/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->Voicemail-Verwaltung] continue=false Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (FAIL) [Voicemail-Verwaltung] ${dialed_extension}(1000) =~ /^(1001|9999)$/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 parsing [default->Local_Extension] continue=false Dialplan: sofia/internal/1001@85.16.246.12:5061 Regex (PASS) [Local_Extension] ${ET_is_local}(true) =~ /^true$/ break=on-false Dialplan: sofia/internal/1001@85.16.246.12:5061 Action set(dialed_extension=${destination_number}) Dialplan: sofia/internal/1001@85.16.246.12:5061 Action export(origination_callee_id_name=hubu) Dialplan: sofia/internal/1001@85.16.246.12:5061 Action export(dialed_extension=${destination_number}) Dialplan: sofia/internal/1001@85.16.246.12:5061 Action set(transfer_ringback=local_stream://moh) Dialplan: sofia/internal/1001@85.16.246.12:5061 Action set(hangup_after_bridge=true) Dialplan: sofia/internal/1001@85.16.246.12:5061 Action export(nolocal:sip_secure_media=${user_data(${dialed_extension}@${domain_name} var sip_secure_media)}) Dialplan: sofia/internal/1001@85.16.246.12:5061 Action db(insert/${domain_name}-last_dial/${called_party_callgroup}/${uuid}) Dialplan: sofia/internal/1001@85.16.246.12:5061 Action set(continue_on_fail=false) Dialplan: sofia/internal/1001@85.16.246.12:5061 Action bridge(user/${dialed_extension}@85.16.246.12) 2009-10-22 17:41:02.436510 [DEBUG] switch_core_state_machine.c:114 (sofia/internal/1001@85.16.246.12:5061) State Change CS_ROUTING -> CS_EXECUTE 2009-10-22 17:41:02.436510 [DEBUG] switch_core_session.c:977 Send signal sofia/internal/1001@85.16.246.12:5061 [BREAK] 2009-10-22 17:41:02.436510 [DEBUG] switch_core_state_machine.c:333 (sofia/internal/1001@85.16.246.12:5061) State ROUTING going to sleep 2009-10-22 17:41:02.436510 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/1001@85.16.246.12:5061) Running State Change CS_EXECUTE 2009-10-22 17:41:02.436510 [DEBUG] switch_core_state_machine.c:340 (sofia/internal/1001@85.16.246.12:5061) State EXECUTE 2009-10-22 17:41:02.436510 [DEBUG] mod_sofia.c:173 sofia/internal/1001@85.16.246.12:5061 SOFIA EXECUTE 2009-10-22 17:41:02.436510 [DEBUG] switch_core_state_machine.c:151 sofia/internal/1001@85.16.246.12:5061 Standard EXECUTE EXECUTE sofia/internal/1001@85.16.246.12:5061 set(dialed_extension=1000) 2009-10-22 17:41:02.436510 [DEBUG] mod_dptools.c:752 sofia/internal/1001@85.16.246.12:5061 SET [dialed_extension]=[1000] EXECUTE sofia/internal/1001@85.16.246.12:5061 set(domain_name=85.16.246.12) 2009-10-22 17:41:02.437526 [DEBUG] mod_dptools.c:752 sofia/internal/1001@85.16.246.12:5061 SET [domain_name]=[85.16.246.12] EXECUTE sofia/internal/1001@85.16.246.12:5061 set(dialed_extension=1000) 2009-10-22 17:41:02.437526 [DEBUG] mod_dptools.c:752 sofia/internal/1001@85.16.246.12:5061 SET [dialed_extension]=[1000] EXECUTE sofia/internal/1001@85.16.246.12:5061 export(origination_callee_id_name=hubu) 2009-10-22 17:41:02.437526 [DEBUG] mod_dptools.c:835 EXPORT [origination_callee_id_name]=[hubu] EXECUTE sofia/internal/1001@85.16.246.12:5061 export(dialed_extension=1000) 2009-10-22 17:41:02.437526 [DEBUG] mod_dptools.c:835 EXPORT [dialed_extension]=[1000] EXECUTE sofia/internal/1001@85.16.246.12:5061 set(transfer_ringback=local_stream://moh) 2009-10-22 17:41:02.438511 [DEBUG] mod_dptools.c:752 sofia/internal/1001@85.16.246.12:5061 SET [transfer_ringback]=[local_stream://moh] EXECUTE sofia/internal/1001@85.16.246.12:5061 set(hangup_after_bridge=true) 2009-10-22 17:41:02.438511 [DEBUG] mod_dptools.c:752 sofia/internal/1001@85.16.246.12:5061 SET [hangup_after_bridge]=[true] EXECUTE sofia/internal/1001@85.16.246.12:5061 export(nolocal:sip_secure_media=) 2009-10-22 17:41:02.461511 [DEBUG] mod_dptools.c:835 EXPORT (REMOTE ONLY) [sip_secure_media]=[UNDEF] EXECUTE sofia/internal/1001@85.16.246.12:5061 db(insert/85.16.246.12-last_dial//4d941750-bf21-11de-9c3f-adfc1789590a) EXECUTE sofia/internal/1001@85.16.246.12:5061 set(continue_on_fail=false) 2009-10-22 17:41:02.654513 [DEBUG] mod_dptools.c:752 sofia/internal/1001@85.16.246.12:5061 SET [continue_on_fail]=[false] EXECUTE sofia/internal/1001@85.16.246.12:5061 bridge(user/1000@85.16.246.12) 2009-10-22 17:41:02.679515 [DEBUG] switch_ivr_originate.c:1163 variable string 0 = [presence_id=1000@85.16.246.12] 2009-10-22 17:41:02.679515 [NOTICE] switch_channel.c:613 New Channel sofia/internal/sip:1000@85.16.245.228:1024 [4dbdc384-bf21-11de-9c3f-adfc1789590a] 2009-10-22 17:41:02.679515 [DEBUG] mod_sofia.c:2981 (sofia/internal/sip:1000@85.16.245.228:1024) State Change CS_NEW -> CS_INIT 2009-10-22 17:41:02.679515 [DEBUG] switch_core_session.c:977 Send signal sofia/internal/sip:1000@85.16.245.228:1024 [BREAK] 2009-10-22 17:41:02.679515 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/sip:1000@85.16.245.228:1024) Running State Change CS_INIT 2009-10-22 17:41:02.680516 [DEBUG] switch_core_state_machine.c:330 (sofia/internal/sip:1000@85.16.245.228:1024) State INIT 2009-10-22 17:41:02.680516 [DEBUG] mod_sofia.c:83 sofia/internal/sip:1000@85.16.245.228:1024 SOFIA INIT send 1310 bytes to udp/[85.16.245.228]:1024 at 15:41:02.681508: ------------------------------------------------------------------------ INVITE sip:1000@85.16.245.228:1024;line=5jkc7koy SIP/2.0 Via: SIP/2.0/UDP 85.16.246.12:5061;rport;branch=z9hG4bKKXH41BcZjB8FS Max-Forwards: 68 From: "1001 an PBX1" <sip:1001@85.16.246.12>;tag=HKSNX8D1pKy6K To: <sip:1000@85.16.245.228:1024;line=5jkc7koy> Call-ID: 251c312d-39c4-122d-3fbf-00144fe6907a CSeq: 121994695 INVITE Contact: <sip:mod_sofia@85.16.246.12:5061> User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15180M Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, REGISTER, REFER, UPDATE, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, precondition, path, replaces Allow-Events: talk, presence, dialog, call-info, sla, include-session-description, presence.winfo, message-summary, refer 2009-10-22 17:41:02.681515 [DEBUG] mod_sofia.c:111 (sofia/internal/sip:1000@85.16.245.228:1024) State Change CS_INIT -> CS_ROUTING 2009-10-22 17:41:02.681515 [DEBUG] switch_core_session.c:977 Send signal sofia/internal/sip:1000@85.16.245.228:1024 [BREAK] Content-Type: application/sdp Content-Disposition: session 2009-10-22 17:41:02.681515 [DEBUG] switch_core_state_machine.c:330 (sofia/internal/sip:1000@85.16.245.228:1024) State INIT going to sleep Content-Length: 325 X-Serialnumber: 0004134002CB 2009-10-22 17:41:02.681515 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/sip:1000@85.16.245.228:1024) Running State Change CS_ROUTING P-Key-Flags: resolution="31x13", keys="4" X-Actually-Support: UPDATE Remote-Party-ID: "1001 an PBX1" <sip:1001@85.16.246.12>;party=calling;screen=yes;privacy=off v=0 o=FreeSWITCH 1256212358 1256212359 IN IP4 85.16.246.12 s=FreeSWITCH c=IN IP4 85.16.246.12 t=0 0 m=audio 13704 RTP/AVP 95 103 9 8 101 13 a=rtpmap:95 CELT/48000 a=rtpmap:103 SPEEX/32000 a=rtpmap:9 G722/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=rtpmap:13 CN/8000 a=ptime:10 ------------------------------------------------------------------------ 2009-10-22 17:41:02.681515 [DEBUG] switch_core_state_machine.c:333 (sofia/internal/sip:1000@85.16.245.228:1024) State ROUTING 2009-10-22 17:41:02.681515 [DEBUG] mod_sofia.c:130 sofia/internal/sip:1000@85.16.245.228:1024 SOFIA ROUTING 2009-10-22 17:41:02.681515 [DEBUG] switch_ivr_originate.c:66 (sofia/internal/sip:1000@85.16.245.228:1024) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2009-10-22 17:41:02.681515 [DEBUG] switch_core_session.c:977 Send signal sofia/internal/sip:1000@85.16.245.228:1024 [BREAK] 2009-10-22 17:41:02.681515 [DEBUG] sofia.c:3493 Channel sofia/internal/sip:1000@85.16.245.228:1024 entering state [calling][0] 2009-10-22 17:41:02.681515 [DEBUG] switch_core_state_machine.c:333 (sofia/internal/sip:1000@85.16.245.228:1024) State ROUTING going to sleep 2009-10-22 17:41:02.681515 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/sip:1000@85.16.245.228:1024) Running State Change CS_CONSUME_MEDIA 2009-10-22 17:41:02.681515 [DEBUG] switch_core_state_machine.c:352 (sofia/internal/sip:1000@85.16.245.228:1024) State CONSUME_MEDIA 2009-10-22 17:41:02.681515 [DEBUG] switch_core_state_machine.c:352 (sofia/internal/sip:1000@85.16.245.228:1024) State CONSUME_MEDIA going to sleep recv 521 bytes from udp/[85.16.245.228]:1024 at 15:41:02.706318: ------------------------------------------------------------------------ SIP/2.0 180 Ringing Via: SIP/2.0/UDP 85.16.246.12:5061;rport=5061;branch=z9hG4bKKXH41BcZjB8FS From: "1001 an PBX1" <sip:1001@85.16.246.12>;tag=HKSNX8D1pKy6K To: <sip:1000@85.16.245.228:1024;line=5jkc7koy>;tag=sdeidvhpxx Call-ID: 251c312d-39c4-122d-3fbf-00144fe6907a CSeq: 121994695 INVITE Contact: <sip:1000@85.16.245.228:1024;line=5jkc7koy>;reg-id=1 Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO, UPDATE Allow-Events: talk, hold, refer, call-info Content-Length: 0 ------------------------------------------------------------------------ 2009-10-22 17:41:02.706514 [DEBUG] sofia.c:3493 Channel sofia/internal/sip:1000@85.16.245.228:1024 entering state [proceeding][180] 2009-10-22 17:41:02.706514 [NOTICE] sofia.c:3557 Ring-Ready sofia/internal/sip:1000@85.16.245.228:1024! 2009-10-22 17:41:02.706514 [DEBUG] switch_ivr_originate.c:388 sofia/internal/1001@85.16.246.12:5061 receive message [RINGING] 2009-10-22 17:41:02.706514 [NOTICE] mod_sofia.c:1525 Ring-Ready sofia/internal/1001@85.16.246.12:5061! 2009-10-22 17:41:02.706514 [DEBUG] switch_core_session.c:647 Send signal sofia/internal/1001@85.16.246.12:5061 [BREAK] 2009-10-22 17:41:02.706514 [NOTICE] switch_ivr_originate.c:388 Ring Ready sofia/internal/1001@85.16.246.12:5061! send 737 bytes to udp/[85.16.245.206]:1024 at 15:41:02.706945: ------------------------------------------------------------------------ SIP/2.0 180 Ringing Via: SIP/2.0/UDP 85.16.245.206:1024;branch=z9hG4bK-i48n75d62qts;rport=1024 From: "1001 an PBX1" <sip:1001@85.16.246.12:5061>;tag=7xpim4o1go To: <sip:1000@85.16.246.12:5061;user=phone>;tag=ga0vUDXXSa8Kr Call-ID: 3c31304b7a80-no9xsnjj0bol CSeq: 1 INVITE Contact: <sip:1000@85.16.246.12:5061;transport=udp> User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15180M Accept: application/sdp Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, REGISTER, REFER, UPDATE, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, precondition, path, replaces Allow-Events: talk, presence, dialog, call-info, sla, include-session-description, presence.winfo, message-summary, refer Content-Length: 0 X-Actually-Support: UPDATE ------------------------------------------------------------------------ 2009-10-22 17:41:02.706514 [DEBUG] sofia.c:3493 Channel sofia/internal/1001@85.16.246.12:5061 entering state [early][180] recv 521 bytes from udp/[85.16.245.228]:1024 at 15:41:03.203506: ------------------------------------------------------------------------ SIP/2.0 180 Ringing Via: SIP/2.0/UDP 85.16.246.12:5061;rport=5061;branch=z9hG4bKKXH41BcZjB8FS From: "1001 an PBX1" <sip:1001@85.16.246.12>;tag=HKSNX8D1pKy6K To: <sip:1000@85.16.245.228:1024;line=5jkc7koy>;tag=sdeidvhpxx Call-ID: 251c312d-39c4-122d-3fbf-00144fe6907a CSeq: 121994695 INVITE Contact: <sip:1000@85.16.245.228:1024;line=5jkc7koy>;reg-id=1 Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO, UPDATE Allow-Events: talk, hold, refer, call-info Content-Length: 0 ------------------------------------------------------------------------ 2009-10-22 17:41:03.203522 [DEBUG] sofia.c:3493 Channel sofia/internal/sip:1000@85.16.245.228:1024 entering state [proceeding][180] recv 521 bytes from udp/[85.16.245.228]:1024 at 15:41:04.203621: ------------------------------------------------------------------------ SIP/2.0 180 Ringing Via: SIP/2.0/UDP 85.16.246.12:5061;rport=5061;branch=z9hG4bKKXH41BcZjB8FS From: "1001 an PBX1" <sip:1001@85.16.246.12>;tag=HKSNX8D1pKy6K To: <sip:1000@85.16.245.228:1024;line=5jkc7koy>;tag=sdeidvhpxx Call-ID: 251c312d-39c4-122d-3fbf-00144fe6907a CSeq: 121994695 INVITE Contact: <sip:1000@85.16.245.228:1024;line=5jkc7koy>;reg-id=1 Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO, UPDATE Allow-Events: talk, hold, refer, call-info Content-Length: 0 ------------------------------------------------------------------------ 2009-10-22 17:41:04.203541 [DEBUG] sofia.c:3493 Channel sofia/internal/sip:1000@85.16.245.228:1024 entering state [proceeding][180] recv 888 bytes from udp/[85.16.245.228]:1024 at 15:41:04.773115: ------------------------------------------------------------------------ SIP/2.0 200 Ok Via: SIP/2.0/UDP 85.16.246.12:5061;rport=5061;branch=z9hG4bKKXH41BcZjB8FS From: "1001 an PBX1" <sip:1001@85.16.246.12>;tag=HKSNX8D1pKy6K To: <sip:1000@85.16.245.228:1024;line=5jkc7koy>;tag=sdeidvhpxx Call-ID: 251c312d-39c4-122d-3fbf-00144fe6907a CSeq: 121994695 INVITE Contact: <sip:1000@85.16.245.228:1024;line=5jkc7koy>;reg-id=1 User-Agent: snom820/LAB_HD_HEAD_11_09_09_11_20 Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO, UPDATE Allow-Events: talk, hold, refer, call-info Supported: timer, 100rel, replaces, from-change Content-Type: application/sdp Content-Length: 242 v=0 o=root 45628059 45628060 IN IP4 85.16.245.228 s=call c=IN IP4 85.16.245.228 t=0 0 m=audio 53220 RTP/AVP 9 8 101 a=rtpmap:9 g722/8000 a=rtpmap:8 pcma/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:10 a=sendrecv ------------------------------------------------------------------------ send 404 bytes to udp/[85.16.245.228]:1024 at 15:41:04.773445: ------------------------------------------------------------------------ ACK sip:1000@85.16.245.228:1024;line=5jkc7koy SIP/2.0 Via: SIP/2.0/UDP 85.16.246.12:5061;rport;branch=z9hG4bKm6aX36v2Fmy2m Max-Forwards: 70 From: "1001 an PBX1" <sip:1001@85.16.246.12>;tag=HKSNX8D1pKy6K To: <sip:1000@85.16.245.228:1024;line=5jkc7koy>;tag=sdeidvhpxx Call-ID: 251c312d-39c4-122d-3fbf-00144fe6907a CSeq: 121994695 ACK Contact: <sip:mod_sofia@85.16.246.12:5061> Content-Length: 0 ------------------------------------------------------------------------ 2009-10-22 17:41:04.773545 [DEBUG] sofia.c:3493 Channel sofia/internal/sip:1000@85.16.245.228:1024 entering state [ready][200] 2009-10-22 17:41:04.773545 [DEBUG] sofia.c:3500 Remote SDP: v=0 o=root 45628059 45628060 IN IP4 85.16.245.228 s=call c=IN IP4 85.16.245.228 t=0 0 m=audio 53220 RTP/AVP 9 8 101 a=rtpmap:9 g722/8000 a=rtpmap:8 pcma/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:10 2009-10-22 17:41:04.773545 [DEBUG] sofia_glue.c:3143 Audio Codec Compare [g722:9:8000:10]/[CELT:95:48000:10] 2009-10-22 17:41:04.773545 [DEBUG] sofia_glue.c:3143 Audio Codec Compare [g722:9:8000:10]/[SPEEX:103:32000:20] 2009-10-22 17:41:04.773545 [DEBUG] sofia_glue.c:3143 Audio Codec Compare [g722:9:8000:10]/[G722:9:8000:20] 2009-10-22 17:41:04.773545 [DEBUG] sofia_glue.c:3143 Audio Codec Compare [g722:9:8000:10]/[PCMA:8:8000:20] 2009-10-22 17:41:04.773545 [DEBUG] sofia_glue.c:3191 Substituting codec G722@10i@8000h 2009-10-22 17:41:04.773545 [DEBUG] sofia_glue.c:2101 Set Codec sofia/internal/sip:1000@85.16.245.228:1024 G722/8000 10 ms 80 samples 2009-10-22 17:41:04.773545 [DEBUG] sofia_glue.c:3103 Set 2833 dtmf payload to 101 2009-10-22 17:41:04.773545 [DEBUG] sofia_glue.c:2335 AUDIO RTP [sofia/internal/sip:1000@85.16.245.228:1024] 85.16.246.12 port 13704 -> 85.16.245.228 port 53220 codec: 9 ms: 10 2009-10-22 17:41:04.773545 [DEBUG] switch_rtp.c:1155 Starting timer [soft] 80 bytes per 10ms 2009-10-22 17:41:04.774778 [DEBUG] switch_channel.c:2105 Send signal sofia/internal/1001@85.16.246.12:5061 [BREAK] 2009-10-22 17:41:04.774778 [NOTICE] sofia.c:3983 Channel [sofia/internal/sip:1000@85.16.245.228:1024] has been answered 2009-10-22 17:41:04.774778 [DEBUG] switch_channel.c:182 sofia/internal/sip:1000@85.16.245.228:1024 receive message [AUDIO_SYNC] 2009-10-22 17:41:04.776545 [DEBUG] switch_ivr_originate.c:2324 sofia/internal/1001@85.16.246.12:5061 receive message [ANSWER] 2009-10-22 17:41:04.776545 [DEBUG] sofia_glue.c:3029 Set Remote Key [1 AES_CM_128_HMAC_SHA1_32 inline:Um06txYC7vwXdZDohXJ15te5hZ/iWmPd4voRbdby] 2009-10-22 17:41:04.776545 [DEBUG] sofia_glue.c:2161 Set Local Key [1 AES_CM_128_HMAC_SHA1_32 inline:EKycEU2CPLFUVM4vFRATL21nluVg3RJhmVwJVwA9] 2009-10-22 17:41:04.776545 [DEBUG] sofia_glue.c:3143 Audio Codec Compare [pcmu:0:8000:20]/[CELT:95:48000:10] 2009-10-22 17:41:04.776545 [DEBUG] sofia_glue.c:3143 Audio Codec Compare [pcmu:0:8000:20]/[SPEEX:103:32000:20] 2009-10-22 17:41:04.776545 [DEBUG] sofia_glue.c:3143 Audio Codec Compare [pcmu:0:8000:20]/[G722:9:8000:20] 2009-10-22 17:41:04.776545 [DEBUG] sofia_glue.c:3143 Audio Codec Compare [pcmu:0:8000:20]/[PCMA:8:8000:20] 2009-10-22 17:41:04.776545 [DEBUG] sofia_glue.c:3143 Audio Codec Compare [pcma:8:8000:20]/[CELT:95:48000:10] 2009-10-22 17:41:04.776545 [DEBUG] sofia_glue.c:3143 Audio Codec Compare [pcma:8:8000:20]/[SPEEX:103:32000:20] 2009-10-22 17:41:04.776545 [DEBUG] sofia_glue.c:3143 Audio Codec Compare [pcma:8:8000:20]/[G722:9:8000:20] 2009-10-22 17:41:04.776545 [DEBUG] sofia_glue.c:3143 Audio Codec Compare [pcma:8:8000:20]/[PCMA:8:8000:20] 2009-10-22 17:41:04.776545 [DEBUG] sofia_glue.c:2101 Set Codec sofia/internal/1001@85.16.246.12:5061 PCMA/8000 20 ms 160 samples 2009-10-22 17:41:04.776545 [DEBUG] sofia_glue.c:3103 Set 2833 dtmf payload to 101 2009-10-22 17:41:04.776545 [DEBUG] sofia_glue.c:3077 Our existing sdp is still good [pcma 85.16.245.206:49852], let's keep it. 2009-10-22 17:41:04.776545 [DEBUG] sofia_glue.c:2335 AUDIO RTP [sofia/internal/1001@85.16.246.12:5061] 85.16.246.12 port 10062 -> 85.16.245.206 port 49852 codec: 8 ms: 20 2009-10-22 17:41:04.776545 [DEBUG] switch_rtp.c:1155 Starting timer [soft] 160 bytes per 20ms 2009-10-22 17:41:04.778545 [INFO] switch_rtp.c:1019 Activating Secure RTP SEND 2009-10-22 17:41:04.778545 [INFO] switch_rtp.c:999 Activating Secure RTP RECV 2009-10-22 17:41:04.778545 [DEBUG] switch_core_sqldb.c:396 Secure Type: srtp:AES_CM_128_HMAC_SHA1_32 2009-10-22 17:41:04.778545 [DEBUG] switch_core_sqldb.c:396 Secure Type: srtp:AES_CM_128_HMAC_SHA1_32 2009-10-22 17:41:04.778545 [NOTICE] sofia_glue.c:2770 Pre-Answer sofia/internal/1001@85.16.246.12:5061! 2009-10-22 17:41:04.778545 [DEBUG] mod_sofia.c:543 Local SDP sofia/internal/1001@85.16.246.12:5061: v=0 o=FreeSWITCH 1256216002 1256216003 IN IP4 85.16.246.12 s=FreeSWITCH c=IN IP4 85.16.246.12 t=0 0 m=audio 10062 RTP/SAVP 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 a=crypto:1 AES_CM_128_HMAC_SHA1_32 inline:EKycEU2CPLFUVM4vFRATL21nluVg3RJhmVwJVwA9 2009-10-22 17:41:04.778545 [DEBUG] switch_core_session.c:647 Send signal sofia/internal/1001@85.16.246.12:5061 [BREAK] 2009-10-22 17:41:04.778545 [NOTICE] switch_ivr_originate.c:2324 Channel [sofia/internal/1001@85.16.246.12:5061] has been answered 2009-10-22 17:41:04.778545 [DEBUG] switch_channel.c:182 sofia/internal/1001@85.16.246.12:5061 receive message [AUDIO_SYNC] 2009-10-22 17:41:04.778545 [DEBUG] switch_ivr_originate.c:2368 Originate Resulted in Success: [sofia/internal/sip:1000@85.16.245.228:1024] 2009-10-22 17:41:04.778545 [DEBUG] switch_channel.c:182 sofia/internal/sip:1000@85.16.245.228:1024 receive message [AUDIO_SYNC] 2009-10-22 17:41:04.778545 [DEBUG] switch_channel.c:182 sofia/internal/1001@85.16.246.12:5061 receive message [AUDIO_SYNC] send 1174 bytes to udp/[85.16.245.206]:1024 at 15:41:04.779182: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 85.16.245.206:1024;branch=z9hG4bK-i48n75d62qts;rport=1024 From: "1001 an PBX1" <sip:1001@85.16.246.12:5061>;tag=7xpim4o1go To: <sip:1000@85.16.246.12:5061;user=phone>;tag=ga0vUDXXSa8Kr Call-ID: 3c31304b7a80-no9xsnjj0bol CSeq: 1 INVITE Contact: <sip:1000@85.16.246.12:5061;transport=udp> User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15180M Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, REGISTER, REFER, UPDATE, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, precondition, path, replaces Allow-Events: talk, presence, dialog, call-info, sla, include-session-description, presence.winfo, message-summary, refer Session-Expires: 3600;refresher=uas Min-SE: 120 Content-Type: application/sdp Content-Disposition: session Content-Length: 354 X-Actually-Support: UPDATE v=0 o=FreeSWITCH 1256216002 1256216003 IN IP4 85.16.246.12 s=FreeSWITCH c=IN IP4 85.16.246.12 t=0 0 m=audio 10062 RTP/SAVP 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=crypto:1 AES_CM_128_HMAC_SHA1_32 inline:EKycEU2CPLFUVM4vFRATL21nluVg3RJhmVwJVwA9 m=audio 0 RTP/AVP 19 ------------------------------------------------------------------------ 2009-10-22 17:41:04.778545 [DEBUG] sofia.c:3493 Channel sofia/internal/1001@85.16.246.12:5061 entering state [completed][200] 2009-10-22 17:41:04.778545 [DEBUG] sofia.c:407 sofia/internal/sip:1000@85.16.245.228:1024 receive message [DISPLAY] send 674 bytes to udp/[85.16.245.228]:1024 at 15:41:04.779630: ------------------------------------------------------------------------ INFO sip:1000@85.16.245.228:1024;line=5jkc7koy SIP/2.0 Via: SIP/2.0/UDP 85.16.246.12:5061;rport;branch=z9hG4bKNF4N51D6cXmNg Max-Forwards: 70 From: "1001 an PBX1" <sip:1001@85.16.246.12>;tag=HKSNX8D1pKy6K To: <sip:1000@85.16.245.228:1024;line=5jkc7koy>;tag=sdeidvhpxx 2009-10-22 17:41:04.779555 [DEBUG] switch_ivr_originate.c:2368 Originate Resulted in Success: [sofia/internal/sip:1000@85.16.245.228:1024] Call-ID: 251c312d-39c4-122d-3fbf-00144fe6907a CSeq: 121994696 INFO Contact: <sip:mod_sofia@85.16.246.12:5061> User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15180M Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, REGISTER, REFER, UPDATE, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, precondition, path, replaces Content-Type: message/sipfrag Content-Length: 27 2009-10-22 17:41:04.779555 [DEBUG] switch_channel.c:182 sofia/internal/sip:1000@85.16.245.228:1024 receive message [AUDIO_SYNC] From: To: "unknown" 1000 ------------------------------------------------------------------------ 2009-10-22 17:41:04.779555 [DEBUG] switch_channel.c:182 sofia/internal/1001@85.16.246.12:5061 receive message [AUDIO_SYNC] 2009-10-22 17:41:04.779555 [DEBUG] switch_ivr_bridge.c:975 sofia/internal/sip:1000@85.16.245.228:1024 receive message [BRIDGE] 2009-10-22 17:41:04.779555 [DEBUG] switch_core_session.c:647 Send signal sofia/internal/sip:1000@85.16.245.228:1024 [BREAK] 2009-10-22 17:41:04.779555 [DEBUG] switch_ivr_bridge.c:982 sofia/internal/1001@85.16.246.12:5061 receive message [BRIDGE] 2009-10-22 17:41:04.779555 [DEBUG] switch_core_session.c:647 Send signal sofia/internal/1001@85.16.246.12:5061 [BREAK] 2009-10-22 17:41:04.779555 [DEBUG] switch_ivr_bridge.c:1026 (sofia/internal/sip:1000@85.16.245.228:1024) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA 2009-10-22 17:41:04.779555 [DEBUG] switch_core_session.c:977 Send signal sofia/internal/sip:1000@85.16.245.228:1024 [BREAK] 2009-10-22 17:41:04.779555 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/sip:1000@85.16.245.228:1024) Running State Change CS_EXCHANGE_MEDIA 2009-10-22 17:41:04.780562 [DEBUG] switch_core_state_machine.c:343 (sofia/internal/sip:1000@85.16.245.228:1024) State EXCHANGE_MEDIA 2009-10-22 17:41:04.780562 [DEBUG] mod_sofia.c:436 SOFIA LOOPBACK recv 405 bytes from udp/[85.16.245.206]:1024 at 15:41:04.819062: ------------------------------------------------------------------------ ACK sip:1000@85.16.246.12:5061;transport=udp SIP/2.0 Via: SIP/2.0/UDP 85.16.245.206:1024;branch=z9hG4bK-ltlzbi82kr2t;rport From: "1001 an PBX1" <sip:1001@85.16.246.12:5061>;tag=7xpim4o1go To: <sip:1000@85.16.246.12:5061;user=phone>;tag=ga0vUDXXSa8Kr Call-ID: 3c31304b7a80-no9xsnjj0bol CSeq: 1 ACK Max-Forwards: 70 Contact: <sip:1001@85.16.245.206:1024;line=eg3wp69a>;reg-id=1 Content-Length: 0 ------------------------------------------------------------------------ 2009-10-22 17:41:04.818545 [DEBUG] sofia.c:3493 Channel sofia/internal/1001@85.16.246.12:5061 entering state [ready][200] 2009-10-22 17:41:04.830545 [DEBUG] switch_ivr_bridge.c:122 sofia/internal/sip:1000@85.16.245.228:1024 receive message [DISPLAY] 2009-10-22 17:41:04.840545 [DEBUG] switch_ivr_bridge.c:122 sofia/internal/1001@85.16.246.12:5061 receive message [DISPLAY] send 670 bytes to udp/[85.16.245.206]:1024 at 15:41:04.840803: ------------------------------------------------------------------------ INFO sip:1001@85.16.245.206:1024;line=eg3wp69a SIP/2.0 Via: SIP/2.0/UDP 85.16.246.12:5061;rport;branch=z9hG4bKprXe7vy995a8B Max-Forwards: 70 From: <sip:1000@85.16.246.12:5061;user=phone>;tag=ga0vUDXXSa8Kr To: "1001 an PBX1" <sip:1001@85.16.246.12:5061>;tag=7xpim4o1go Call-ID: 3c31304b7a80-no9xsnjj0bol CSeq: 121994696 INFO Contact: <sip:1000@85.16.246.12:5061;transport=udp> User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15180M Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, REGISTER, REFER, UPDATE, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, precondition, path, replaces Content-Type: message/sipfrag Content-Length: 24 From: To: "hubu" 1000 ------------------------------------------------------------------------ 2009-10-22 17:41:04.860546 [DEBUG] switch_core_io.c:652 sofia/internal/sip:1000@85.16.245.228:1024 receive message [TRANSCODING_NECESSARY] 2009-10-22 17:41:04.860546 [DEBUG] switch_core_io.c:891 Engaging Write Buffer at 320 bytes to accommodate 640->320 2009-10-22 17:41:04.930547 [DEBUG] switch_core_io.c:652 sofia/internal/1001@85.16.246.12:5061 receive message [TRANSCODING_NECESSARY] 2009-10-22 17:41:04.930547 [DEBUG] switch_core_io.c:891 Engaging Write Buffer at 320 bytes to accommodate 320->320 2009-10-22 17:41:05.000550 [WARNING] mod_sofia.c:808 We were told to use ptime 10 but what they meant to say was 20 This issue has so far been identified to happen on the following broken platforms/devices: Linksys/Sipura aka Cisco ShoreTel Sonus/L3 We will try to fix it but some of the devices on this list are so broken who knows what will happen.. 2009-10-22 17:41:05.000550 [DEBUG] sofia_glue.c:2101 Set Codec sofia/internal/sip:1000@85.16.245.228:1024 G722/8000 20 ms 160 samples 2009-10-22 17:41:05.000550 [DEBUG] switch_rtp.c:1064 RE-Starting timer [soft] 160 bytes per 20000ms recv 362 bytes from udp/[85.16.245.206]:1024 at 15:41:05.019892: ------------------------------------------------------------------------ SIP/2.0 200 Ok Via: SIP/2.0/UDP 85.16.246.12:5061;rport=5061;branch=z9hG4bKprXe7vy995a8B From: <sip:1000@85.16.246.12:5061;user=phone>;tag=ga0vUDXXSa8Kr To: "1001 an PBX1" <sip:1001@85.16.246.12:5061>;tag=7xpim4o1go Call-ID: 3c31304b7a80-no9xsnjj0bol CSeq: 121994696 INFO Contact: <sip:1001@85.16.245.206:1024;line=eg3wp69a>;reg-id=1 Content-Length: 0 ------------------------------------------------------------------------ recv 372 bytes from udp/[85.16.245.228]:1024 at 15:41:05.177982: ------------------------------------------------------------------------ SIP/2.0 200 Ok Via: SIP/2.0/UDP 85.16.246.12:5061;rport=5061;branch=z9hG4bKNF4N51D6cXmNg From: "1001 an PBX1" <sip:1001@85.16.246.12>;tag=HKSNX8D1pKy6K To: <sip:1000@85.16.245.228:1024;line=5jkc7koy>;tag=sdeidvhpxx Call-ID: 251c312d-39c4-122d-3fbf-00144fe6907a CSeq: 121994696 INFO Contact: <sip:1000@85.16.245.228:1024;line=5jkc7koy>;reg-id=1 Content-Length: 0 ------------------------------------------------------------------------ send 679 bytes to udp/[85.16.245.228]:1024 at 15:41:05.178268: ------------------------------------------------------------------------ INFO sip:1000@85.16.245.228:1024;line=5jkc7koy SIP/2.0 Via: SIP/2.0/UDP 85.16.246.12:5061;rport;branch=z9hG4bKQ1p78QFD7e1tQ Max-Forwards: 70 From: "1001 an PBX1" <sip:1001@85.16.246.12>;tag=HKSNX8D1pKy6K To: <sip:1000@85.16.245.228:1024;line=5jkc7koy>;tag=sdeidvhpxx Call-ID: 251c312d-39c4-122d-3fbf-00144fe6907a CSeq: 121994697 INFO Contact: <sip:mod_sofia@85.16.246.12:5061> User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15180M Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, REGISTER, REFER, UPDATE, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, precondition, path, replaces Content-Type: message/sipfrag Content-Length: 32 From: To: "1001 an PBX1" 1001 ------------------------------------------------------------------------ recv 372 bytes from udp/[85.16.245.228]:1024 at 15:41:05.271275: ------------------------------------------------------------------------ SIP/2.0 200 Ok Via: SIP/2.0/UDP 85.16.246.12:5061;rport=5061;branch=z9hG4bKQ1p78QFD7e1tQ From: "1001 an PBX1" <sip:1001@85.16.246.12>;tag=HKSNX8D1pKy6K To: <sip:1000@85.16.245.228:1024;line=5jkc7koy>;tag=sdeidvhpxx Call-ID: 251c312d-39c4-122d-3fbf-00144fe6907a CSeq: 121994697 INFO Contact: <sip:1000@85.16.245.228:1024;line=5jkc7koy>;reg-id=1 Content-Length: 0 ------------------------------------------------------------------------ recv 589 bytes from udp/[85.16.245.228]:1024 at 15:41:06.336858: ------------------------------------------------------------------------ BYE sip:mod_sofia@85.16.246.12:5061 SIP/2.0 Via: SIP/2.0/UDP 85.16.245.228:1024;branch=z9hG4bK-quadh3ddon5i;rport From: <sip:1000@85.16.245.228:1024;line=5jkc7koy>;tag=sdeidvhpxx To: "1001 an PBX1" <sip:1001@85.16.246.12>;tag=HKSNX8D1pKy6K Call-ID: 251c312d-39c4-122d-3fbf-00144fe6907a CSeq: 1 BYE Max-Forwards: 70 Contact: <sip:1000@85.16.245.228:1024;line=5jkc7koy>;reg-id=1 User-Agent: snom820/LAB_HD_HEAD_11_09_09_11_20 RTP-RxStat: Total_Rx_Pkts=0,Rx_Pkts=66,Rx_Pkts_Lost=1,Remote_Rx_Pkts_Lost=0 RTP-TxStat: Total_Tx_Pkts=67,Tx_Pkts=62,Remote_Tx_Pkts=0 Content-Length: 0 ------------------------------------------------------------------------ 2009-10-22 17:41:06.336568 [NOTICE] sofia.c:328 Hangup sofia/internal/sip:1000@85.16.245.228:1024 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING] 2009-10-22 17:41:06.336568 [DEBUG] switch_channel.c:1896 Send signal sofia/internal/sip:1000@85.16.245.228:1024 [KILL] 2009-10-22 17:41:06.336568 [DEBUG] switch_core_session.c:977 Send signal sofia/internal/sip:1000@85.16.245.228:1024 [BREAK] 2009-10-22 17:41:06.336568 [DEBUG] switch_core_state_machine.c:449 thread mismatch skipping state handler. send 510 bytes to udp/[85.16.245.228]:1024 at 15:41:06.337536: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 85.16.245.228:1024;branch=z9hG4bK-quadh3ddon5i;rport=1024 From: <sip:1000@85.16.245.228:1024;line=5jkc7koy>;tag=sdeidvhpxx To: "1001 an PBX1" <sip:1001@85.16.246.12>;tag=HKSNX8D1pKy6K Call-ID: 251c312d-39c4-122d-3fbf-00144fe6907a CSeq: 1 BYE User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15180M Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, REGISTER, REFER, UPDATE, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, precondition, path, replaces Content-Length: 0 ------------------------------------------------------------------------ 2009-10-22 17:41:06.340568 [DEBUG] switch_ivr_bridge.c:453 sofia/internal/sip:1000@85.16.245.228:1024 ending bridge by request from read function 2009-10-22 17:41:06.340568 [DEBUG] switch_ivr_bridge.c:447 sofia/internal/sip:1000@85.16.245.228:1024 ending bridge by request from write function 2009-10-22 17:41:06.340568 [DEBUG] switch_ivr_bridge.c:528 BRIDGE THREAD DONE [sofia/internal/sip:1000@85.16.245.228:1024] 2009-10-22 17:41:06.340568 [DEBUG] switch_ivr_bridge.c:530 Send signal sofia/internal/1001@85.16.246.12:5061 [BREAK] 2009-10-22 17:41:06.340568 [DEBUG] switch_core_state_machine.c:343 (sofia/internal/sip:1000@85.16.245.228:1024) State EXCHANGE_MEDIA going to sleep 2009-10-22 17:41:06.340568 [DEBUG] switch_ivr_bridge.c:502 sofia/internal/1001@85.16.246.12:5061 receive message [UNBRIDGE] 2009-10-22 17:41:06.340568 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/sip:1000@85.16.245.228:1024) Running State Change CS_HANGUP 2009-10-22 17:41:06.340568 [DEBUG] switch_core_session.c:647 Send signal sofia/internal/1001@85.16.246.12:5061 [BREAK] 2009-10-22 17:41:06.340568 [DEBUG] switch_ivr_bridge.c:528 BRIDGE THREAD DONE [sofia/internal/1001@85.16.246.12:5061] 2009-10-22 17:41:06.340568 [DEBUG] switch_ivr_bridge.c:530 Send signal sofia/internal/sip:1000@85.16.245.228:1024 [BREAK] 2009-10-22 17:41:06.340568 [NOTICE] switch_ivr_bridge.c:1102 Hangup sofia/internal/1001@85.16.246.12:5061 [CS_EXECUTE] [NORMAL_CLEARING] 2009-10-22 17:41:06.340568 [DEBUG] switch_channel.c:1896 Send signal sofia/internal/1001@85.16.246.12:5061 [KILL] 2009-10-22 17:41:06.340568 [DEBUG] switch_core_session.c:977 Send signal sofia/internal/1001@85.16.246.12:5061 [BREAK] 2009-10-22 17:41:06.340568 [DEBUG] switch_core_state_machine.c:476 (sofia/internal/sip:1000@85.16.245.228:1024) State HANGUP 2009-10-22 17:41:06.340568 [DEBUG] mod_sofia.c:344 Channel sofia/internal/sip:1000@85.16.245.228:1024 hanging up, cause: NORMAL_CLEARING 2009-10-22 17:41:06.341609 [DEBUG] switch_core_state_machine.c:46 sofia/internal/sip:1000@85.16.245.228:1024 Standard HANGUP, cause: NORMAL_CLEARING 2009-10-22 17:41:06.341609 [DEBUG] switch_core_state_machine.c:476 (sofia/internal/sip:1000@85.16.245.228:1024) State HANGUP going to sleep 2009-10-22 17:41:06.341609 [DEBUG] switch_core_state_machine.c:325 (sofia/internal/sip:1000@85.16.245.228:1024) State Change CS_HANGUP -> CS_REPORTING 2009-10-22 17:41:06.341609 [DEBUG] switch_core_state_machine.c:476 (sofia/internal/1001@85.16.246.12:5061) State HANGUP 2009-10-22 17:41:06.341609 [DEBUG] switch_core_session.c:977 Send signal sofia/internal/sip:1000@85.16.245.228:1024 [BREAK] 2009-10-22 17:41:06.341609 [DEBUG] mod_sofia.c:344 Channel sofia/internal/1001@85.16.246.12:5061 hanging up, cause: NORMAL_CLEARING 2009-10-22 17:41:06.341609 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/sip:1000@85.16.245.228:1024) Running State Change CS_REPORTING 2009-10-22 17:41:06.341609 [DEBUG] mod_sofia.c:382 Sending BYE to sofia/internal/1001@85.16.246.12:5061 2009-10-22 17:41:06.341609 [DEBUG] switch_core_state_machine.c:567 (sofia/internal/sip:1000@85.16.245.228:1024) State REPORTING 2009-10-22 17:41:06.341609 [DEBUG] switch_core_state_machine.c:53 sofia/internal/sip:1000@85.16.245.228:1024 Standard REPORTING, cause: NORMAL_CLEARING 2009-10-22 17:41:06.341609 [DEBUG] switch_core_state_machine.c:567 (sofia/internal/sip:1000@85.16.245.228:1024) State REPORTING going to sleep 2009-10-22 17:41:06.341609 [DEBUG] switch_core_state_machine.c:319 (sofia/internal/sip:1000@85.16.245.228:1024) State Change CS_REPORTING - -> CS_DESTROY 2009-10-22 17:41:06.341609 [DEBUG] switch_core_session.c:977 Send signal sofia/internal/sip:1000@85.16.245.228:1024 [BREAK] 2009-10-22 17:41:06.341609 [DEBUG] switch_core_session.c:1114 Session 42 (sofia/internal/sip:1000@85.16.245.228:1024) Locked, Waiting on external entities 2009-10-22 17:41:06.341609 [DEBUG] switch_core_state_machine.c:46 sofia/internal/1001@85.16.246.12:5061 Standard HANGUP, cause: NORMAL_CLEARING 2009-10-22 17:41:06.341609 [DEBUG] switch_core_state_machine.c:476 (sofia/internal/1001@85.16.246.12:5061) State HANGUP going to sleep 2009-10-22 17:41:06.341609 [NOTICE] switch_core_session.c:1132 Session 42 (sofia/internal/sip:1000@85.16.245.228:1024) Ended 2009-10-22 17:41:06.341609 [NOTICE] switch_core_session.c:1134 Close Channel sofia/internal/sip:1000@85.16.245.228:1024 [CS_DESTROY] 2009-10-22 17:41:06.341609 [DEBUG] switch_core_state_machine.c:413 (sofia/internal/sip:1000@85.16.245.228:1024) Running State Change CS_DESTROY 2009-10-22 17:41:06.341609 [DEBUG] switch_core_state_machine.c:424 (sofia/internal/sip:1000@85.16.245.228:1024) State DESTROY 2009-10-22 17:41:06.341609 [DEBUG] mod_sofia.c:261 sofia/internal/sip:1000@85.16.245.228:1024 SOFIA DESTROY 2009-10-22 17:41:06.341609 [DEBUG] switch_core_state_machine.c:340 (sofia/internal/1001@85.16.246.12:5061) State EXECUTE going to sleep 2009-10-22 17:41:06.341609 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/1001@85.16.246.12:5061) Running State Change CS_HANGUP 2009-10-22 17:41:06.342645 [DEBUG] switch_core_state_machine.c:454 handler already called, skipping state handler. 2009-10-22 17:41:06.341609 [DEBUG] switch_core_state_machine.c:60 sofia/internal/sip:1000@85.16.245.228:1024 Standard DESTROY 2009-10-22 17:41:06.342645 [DEBUG] switch_core_state_machine.c:424 (sofia/internal/sip:1000@85.16.245.228:1024) State DESTROY going to sleep send 659 bytes to udp/[85.16.245.206]:1024 at 15:41:06.342919: ------------------------------------------------------------------------ BYE sip:1001@85.16.245.206:1024;line=eg3wp69a SIP/2.02009-10-22 17:41:06.342645 [DEBUG] switch_core_state_machine.c:325 (sofia/internal/1001@85.16.246.12:5061) State Change CS_HANGUP -> CS_REPORTING 2009-10-22 17:41:06.342645 [DEBUG] switch_core_session.c:977 Send signal sofia/internal/1001@85.16.246.12:5061 [BREAK] 2009-10-22 17:41:06.342645 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/1001@85.16.246.12:5061) Running State Change CS_REPORTING 2009-10-22 17:41:06.342645 [DEBUG] switch_core_state_machine.c:567 (sofia/internal/1001@85.16.246.12:5061) State REPORTING Via: SIP/2.0/UDP 85.16.246.12:5061;rport;branch=z9hG4bKrag0aK0g4QQDK Max-Forwards: 70 From: <sip:1000@85.16.246.12:5061;user=phone>;tag=ga0vUDXXSa8Kr To: "1001 an PBX1" <sip:1001@85.16.246.12:5061>;tag=7xpim4o1go Call-ID: 3c31304b7a80-no9xsnjj0bol CSeq: 121994697 BYE Contact: <sip:1000@85.16.246.12:5061;transport=udp> User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15180M Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, REGISTER, REFER, UPDATE, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, precondition, path, replaces Reason: Q.850;cause=16;text="NORMAL_CLEARING" Content-Length: 0 ------------------------------------------------------------------------ 2009-10-22 17:41:06.343570 [DEBUG] switch_core_state_machine.c:53 sofia/internal/1001@85.16.246.12:5061 Standard REPORTING, cause: NORMAL_CLEARING 2009-10-22 17:41:06.343570 [DEBUG] switch_core_state_machine.c:567 (sofia/internal/1001@85.16.246.12:5061) State REPORTING going to sleep 2009-10-22 17:41:06.343570 [DEBUG] switch_core_state_machine.c:319 (sofia/internal/1001@85.16.246.12:5061) State Change CS_REPORTING -> CS_DESTROY 2009-10-22 17:41:06.343570 [DEBUG] switch_core_session.c:977 Send signal sofia/internal/1001@85.16.246.12:5061 [BREAK] 2009-10-22 17:41:06.343570 [DEBUG] switch_core_session.c:1114 Session 41 (sofia/internal/1001@85.16.246.12:5061) Locked, Waiting on external entities 2009-10-22 17:41:06.343570 [NOTICE] switch_core_session.c:1132 Session 41 (sofia/internal/1001@85.16.246.12:5061) Ended 2009-10-22 17:41:06.343570 [NOTICE] switch_core_session.c:1134 Close Channel sofia/internal/1001@85.16.246.12:5061 [CS_DESTROY] 2009-10-22 17:41:06.343570 [DEBUG] switch_core_state_machine.c:413 (sofia/internal/1001@85.16.246.12:5061) Running State Change CS_DESTROY 2009-10-22 17:41:06.343570 [DEBUG] switch_core_state_machine.c:424 (sofia/internal/1001@85.16.246.12:5061) State DESTROY 2009-10-22 17:41:06.343570 [DEBUG] mod_sofia.c:261 sofia/internal/1001@85.16.246.12:5061 SOFIA DESTROY 2009-10-22 17:41:06.343570 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1001@85.16.246.12:5061 Standard DESTROY 2009-10-22 17:41:06.343570 [DEBUG] switch_core_state_machine.c:424 (sofia/internal/1001@85.16.246.12:5061) State DESTROY going to sleep recv 524 bytes from udp/[85.16.245.206]:1024 at 15:41:06.357848: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 85.16.246.12:5061;rport=5061;branch=z9hG4bKrag0aK0g4QQDK From: <sip:1000@85.16.246.12:5061;user=phone>;tag=ga0vUDXXSa8Kr To: "1001 an PBX1" <sip:1001@85.16.246.12:5061>;tag=7xpim4o1go Call-ID: 3c31304b7a80-no9xsnjj0bol CSeq: 121994697 BYE Contact: <sip:1001@85.16.245.206:1024;line=eg3wp69a>;reg-id=1 User-Agent: snom820/8.2.16 RTP-RxStat: Total_Rx_Pkts=61,Rx_Pkts=61,Rx_Pkts_Lost=0,Remote_Rx_Pkts_Lost=0 RTP-TxStat: Total_Tx_Pkts=0,Tx_Pkts=90,Remote_Tx_Pkts=0 Content-Length: 0 ------------------------------------------------------------------------ On 21.10.2009 19:48, Michael Collins wrote:

Okay, you are able to reproduce that "unknown" thing? Can you pastebin a fresh debug log w/ SIP trace on, plus and relevant dp changes from the default dialplan? Thanks, MC

-----BEGIN PGP SIGNATURE----- Version: GnuPG v1.4.7 (MingW32) iD8DBQFK4H3z4tZeNddg3dwRAtR3AJ4xUr8Tp4ctmhQn2DIQSvrBSv59QQCeLSsH 5inLJYyajrsYT2+kXcgd22w= =5B1x -----END PGP SIGNATURE-----

I'm sure that problem is gone in svn trunk. On Thu, Oct 22, 2009 at 11:25 AM, Brian West <brian@freeswitch.org> wrote:

I can't get what exactly you re talking about. Can you clarify ? Also please include the packets of interest only not the full trace if its not relevant to the bug.

/b

On Oct 22, 2009, at 10:44 AM, Helmut Kuper wrote:

...

-----BEGIN PGP SIGNED MESSAGE----- Hash: SHA1

Hi Mike,

here it is:

Dialplan:

<extension name="Local_Extension"> <condition field="destination_number" expression="^(10[01][0-9]) $"> <action application="set" data="dialed_extension=$1"/> <action application="export" data="dialed_extension=$1"/> <action application="set" data="transfer_ringback=$$ {hold_music}"/> <action application="set" data="hangup_after_bridge=true"/> <action application="bridge" data="user/${dialed_extension}@${domain_name}"/> </condition> </extension>

_______________________________________________ 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> iax:guest@conference.freeswitch.org/888 googletalk:conf+888@conference.freeswitch.org<googletalk%3Aconf%2B888@conference.freeswitch.org> pstn:213-799-1400

should be even better in 15210 On Fri, Oct 23, 2009 at 6:35 AM, Helmut Kuper <helmut.kuper@ewetel.de>wrote:

-----BEGIN PGP SIGNED MESSAGE----- Hash: SHA1

Hi Anthony,

thanks! The "unknown" thing is gone as long as you don't use "originate_callee_id_name"

The "originate_callee_id_name" chvar causes caller's AND callee's display ("caller name" line)to be updated with the content of that chvar as soon as you set it.

Let's say you use "hubu" as originate_callee_id_name. As soon as callee has picked up the phone it's display ("caller name" line) is updated with "hubu" and right after that with caller's name. The caller's display ("callee name" line) is updated to "hubu" (as expected).

So it's still not nice to see a name switch in callee's display for caller's name after picking up.

In the following you see the SIP flows from

a) caller to FS b) FS to callee

The more interesting one is b) I guess

####################################################### This is the sip flow from caller to FS, which is OK:

INVITE sip:1000@85.16.246.12:5061;user=phone SIP/2.0 23/Oct/2009-13:26:44.730

INVITE sip:1000@85.16.246.12:5061;user=phone SIP/2.0 Via: SIP/2.0/UDP 85.16.245.206:1024;branch=z9hG4bK-zdcpisfjx11n;rport From: "1001 an PBX1" <sip:1001@85.16.246.12:5061>;tag=724ehd063s To: <sip:1000@85.16.246.12:5061;user=phone> Call-ID: 3c32462e758f-y19kuulwdknf CSeq: 1 INVITE Max-Forwards: 70 Contact: <sip:1001@85.16.245.206:1024;line=eg3wp69a>;reg-id=1 X-Serialnumber: 0004134002CB P-Key-Flags: resolution="31x13", keys="4" User-Agent: snom820/8.2.16 Accept: application/sdp Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO, UPDATE Allow-Events: talk, hold, refer, call-info Supported: timer, 100rel, replaces, from-change Session-Expires: 3600;refresher=uas Min-SE: 90 Content-Type: application/sdp Content-Length: 734

v=0 o=root 1420196469 1420196469 IN IP4 85.16.245.206 s=call c=IN IP4 85.16.245.206 t=0 0 m=audio 51286 RTP/SAVP 0 8 9 99 3 18 4 101 a=crypto:1 AES_CM_128_HMAC_SHA1_32 inline:CjdPN1m2iLKAyXrQ2pWkQfCMtiISlF94ItTdsDis a=rtpmap:0 pcmu/8000 a=rtpmap:8 pcma/8000 a=rtpmap:9 g722/8000 a=rtpmap:99 g726-32/8000 a=rtpmap:3 gsm/8000 a=rtpmap:18 g729/8000 a=rtpmap:4 g723/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv m=audio 51286 RTP/AVP 0 8 9 99 3 18 4 101 a=rtpmap:0 pcmu/8000 a=rtpmap:8 pcma/8000 a=rtpmap:9 g722/8000 a=rtpmap:99 g726-32/8000 a=rtpmap:3 gsm/8000 a=rtpmap:18 g729/8000 a=rtpmap:4 g723/8000 a=rtpmap:101 telephone-ev

SIP/2.0 100 Trying 23/Oct/2009-13:26:44.731

SIP/2.0 100 Trying Via: SIP/2.0/UDP 85.16.245.206:1024;branch=z9hG4bK-zdcpisfjx11n;rport=1024 From: "1001 an PBX1" <sip:1001@85.16.246.12:5061>;tag=724ehd063s To: <sip:1000@85.16.246.12:5061;user=phone> Call-ID: 3c32462e758f-y19kuulwdknf CSeq: 1 INVITE User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15203M Content-Length: 0

SIP/2.0 180 Ringing 23/Oct/2009-13:26:45.3

SIP/2.0 180 Ringing Via: SIP/2.0/UDP 85.16.245.206:1024;branch=z9hG4bK-zdcpisfjx11n;rport=1024 From: "1001 an PBX1" <sip:1001@85.16.246.12:5061>;tag=724ehd063s To: <sip:1000@85.16.246.12:5061;user=phone>;tag=QD9eD62NH0gQj Call-ID: 3c32462e758f-y19kuulwdknf CSeq: 1 INVITE Contact: <sip:1000@85.16.246.12:5061;transport=udp> User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15203M Accept: application/sdp Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, precondition, path, replaces Allow-Events: talk, presence, dialog, call-info, sla, include-session-description, presence.winfo, message-summary, refer Content-Length: 0 X-FS-Display-Name: hubu X-FS-Display-Number: X-FS-Support: update_display

SIP/2.0 200 OK 23/Oct/2009-13:26:47.367

SIP/2.0 200 OK Via: SIP/2.0/UDP 85.16.245.206:1024;branch=z9hG4bK-zdcpisfjx11n;rport=1024 From: "1001 an PBX1" <sip:1001@85.16.246.12:5061>;tag=724ehd063s To: <sip:1000@85.16.246.12:5061;user=phone>;tag=QD9eD62NH0gQj Call-ID: 3c32462e758f-y19kuulwdknf CSeq: 1 INVITE Contact: <sip:1000@85.16.246.12:5061;transport=udp> User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15203M Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, precondition, path, replaces Allow-Events: talk, presence, dialog, call-info, sla, include-session-description, presence.winfo, message-summary, refer Session-Expires: 3600;refresher=uas Min-SE: 120 Content-Type: application/sdp Content-Disposition: session Content-Length: 354 X-FS-Display-Name: hubu X-FS-Display-Number: X-FS-Support: update_display

v=0 o=FreeSWITCH 1256285701 1256285702 IN IP4 85.16.246.12 s=FreeSWITCH c=IN IP4 85.16.246.12 t=0 0 m=audio 11506 RTP/SAVP 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=crypto:1 AES_CM_128_HMAC_SHA1_32 inline:/BFm/LUmDBuao0wqg6MS/l4acfzOHoHRFQr3N0R9 m=audio 0 RTP/AVP 19

ACK sip:1000@85.16.246.12:5061;transport=udp SIP/2.0 23/Oct/2009-13:26:47.392

ACK sip:1000@85.16.246.12:5061;transport=udp SIP/2.0 Via: SIP/2.0/UDP 85.16.245.206:1024;branch=z9hG4bK-d474to81d7gh;rport From: "1001 an PBX1" <sip:1001@85.16.246.12:5061>;tag=724ehd063s To: <sip:1000@85.16.246.12:5061;user=phone>;tag=QD9eD62NH0gQj Call-ID: 3c32462e758f-y19kuulwdknf CSeq: 1 ACK Max-Forwards: 70 Contact: <sip:1001@85.16.245.206:1024;line=eg3wp69a>;reg-id=1 Content-Length: 0

INFO sip:1001@85.16.245.206:1024;line=eg3wp69a SIP/2.0 23/Oct/2009-13:26:47.416

INFO sip:1001@85.16.245.206:1024;line=eg3wp69a SIP/2.0 Via: SIP/2.0/UDP 85.16.246.12:5061;rport;branch=z9hG4bKB244eKZ08Uj3H Max-Forwards: 70 From: <sip:1000@85.16.246.12:5061;user=phone>;tag=QD9eD62NH0gQj To: "1001 an PBX1" <sip:1001@85.16.246.12:5061>;tag=724ehd063s Call-ID: 3c32462e758f-y19kuulwdknf CSeq: 122030267 INFO Contact: <sip:1000@85.16.246.12:5061;transport=udp> User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15203M Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, precondition, path, replaces Content-Type: message/sipfrag Content-Length: 24

From: To: "hubu" 1000

SIP/2.0 200 Ok 23/Oct/2009-13:26:47.619

SIP/2.0 200 Ok Via: SIP/2.0/UDP 85.16.246.12:5061;rport=5061;branch=z9hG4bKB244eKZ08Uj3H From: <sip:1000@85.16.246.12:5061;user=phone>;tag=QD9eD62NH0gQj To: "1001 an PBX1" <sip:1001@85.16.246.12:5061>;tag=724ehd063s Call-ID: 3c32462e758f-y19kuulwdknf CSeq: 122030267 INFO Contact: <sip:1001@85.16.245.206:1024;line=eg3wp69a>;reg-id=1 Content-Length: 0

BYE sip:1001@85.16.245.206:1024;line=eg3wp69a SIP/2.0 23/Oct/2009-13:26:49.18

BYE sip:1001@85.16.245.206:1024;line=eg3wp69a SIP/2.0 Via: SIP/2.0/UDP 85.16.246.12:5061;rport;branch=z9hG4bKDmQpj9072DZ8r Max-Forwards: 70 From: <sip:1000@85.16.246.12:5061;user=phone>;tag=QD9eD62NH0gQj To: "1001 an PBX1" <sip:1001@85.16.246.12:5061>;tag=724ehd063s Call-ID: 3c32462e758f-y19kuulwdknf CSeq: 122030268 BYE Contact: <sip:1000@85.16.246.12:5061;transport=udp> User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15203M Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, precondition, path, replaces Reason: Q.850;cause=16;text="NORMAL_CLEARING" Content-Length: 0

SIP/2.0 200 OK 23/Oct/2009-13:26:49.52

SIP/2.0 200 OK Via: SIP/2.0/UDP 85.16.246.12:5061;rport=5061;branch=z9hG4bKDmQpj9072DZ8r From: <sip:1000@85.16.246.12:5061;user=phone>;tag=QD9eD62NH0gQj To: "1001 an PBX1" <sip:1001@85.16.246.12:5061>;tag=724ehd063s Call-ID: 3c32462e758f-y19kuulwdknf CSeq: 122030268 BYE Contact: <sip:1001@85.16.245.206:1024;line=eg3wp69a>;reg-id=1 User-Agent: snom820/8.2.16 RTP-RxStat: Total_Rx_Pkts=65,Rx_Pkts=65,Rx_Pkts_Lost=0,Remote_Rx_Pkts_Lost=0 RTP-TxStat: Total_Tx_Pkts=0,Tx_Pkts=94,Remote_Tx_Pkts=0 Content-Length: 0

####################################################################

This is the SIP flow from FS to callee.

The first INFO sent from FS to callee is the Problem. It contains "hubu" as "To:" header. If there is s need for this message I guess the "From" header should be changed, because from the callee-phone's point of view the "To:" header is the caller and the "From" header is the callee. In my opinion this INFO message shouldn't be sent at all, but I don't know what you intend it to do.

INVITE sip:1000@85.16.245.228:1024;line=5jkc7koy SIP/2.0 23/Oct/2009-13:26:44.978

INVITE sip:1000@85.16.245.228:1024;line=5jkc7koy SIP/2.0 Via: SIP/2.0/UDP 85.16.246.12:5061;rport;branch=z9hG4bK86rt91cpH1FBF Max-Forwards: 68 From: "1001 an PBX1" <sip:1001@85.16.246.12 <sip%3A1001@85.16.246.12>

...

;tag=rp27e1KSe969D To: <sip:1000@85.16.245.228:1024;line=5jkc7koy> Call-ID: c93948e5-3a69-122d-94bc-00144fe6907a CSeq: 122030266 INVITE Contact: <sip:mod_sofia@85.16.246.12:5061> User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15203M Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, precondition, path, replaces Allow-Events: talk, presence, dialog, call-info, sla, include-session-description, presence.winfo, message-summary, refer Content-Type: application/sdp Content-Disposition: session Content-Length: 325 X-Serialnumber: 0004134002CB P-Key-Flags: resolution="31x13", keys="4" X-FS-Support: update_display Remote-Party-ID: "1001 an PBX1" <sip:1001@85.16.246.12 <sip%3A1001@85.16.246.12> ;party=calling;screen=yes;privacy=off

v=0 o=FreeSWITCH 1256285400 1256285401 IN IP4 85.16.246.12 s=FreeSWITCH c=IN IP4 85.16.246.12 t=0 0 m=audio 11804 RTP/AVP 95 103 9 8 101 13 a=rtpmap:95 CELT/48000 a=rtpmap:103 SPEEX/32000 a=rtpmap:9 G722/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=rtpmap:13 CN/8000 a=ptime:10

SIP/2.0 180 Ringing 23/Oct/2009-13:26:45.2

SIP/2.0 180 Ringing Via: SIP/2.0/UDP 85.16.246.12:5061;rport=5061;branch=z9hG4bK86rt91cpH1FBF From: "1001 an PBX1" <sip:1001@85.16.246.12 <sip%3A1001@85.16.246.12>

...

;tag=rp27e1KSe969D To: <sip:1000@85.16.245.228:1024;line=5jkc7koy>;tag=a7mygnrad4 Call-ID: c93948e5-3a69-122d-94bc-00144fe6907a CSeq: 122030266 INVITE Contact: <sip:1000@85.16.245.228:1024;line=5jkc7koy>;reg-id=1 Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO, UPDATE Allow-Events: talk, hold, refer, call-info Content-Length: 0

SIP/2.0 180 Ringing 23/Oct/2009-13:26:45.504

SIP/2.0 180 Ringing Via: SIP/2.0/UDP 85.16.246.12:5061;rport=5061;branch=z9hG4bK86rt91cpH1FBF From: "1001 an PBX1" <sip:1001@85.16.246.12 <sip%3A1001@85.16.246.12>

...

;tag=rp27e1KSe969D To: <sip:1000@85.16.245.228:1024;line=5jkc7koy>;tag=a7mygnrad4 Call-ID: c93948e5-3a69-122d-94bc-00144fe6907a CSeq: 122030266 INVITE Contact: <sip:1000@85.16.245.228:1024;line=5jkc7koy>;reg-id=1 Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO, UPDATE Allow-Events: talk, hold, refer, call-info Content-Length: 0

SIP/2.0 180 Ringing 23/Oct/2009-13:26:46.504

SIP/2.0 180 Ringing Via: SIP/2.0/UDP 85.16.246.12:5061;rport=5061;branch=z9hG4bK86rt91cpH1FBF From: "1001 an PBX1" <sip:1001@85.16.246.12 <sip%3A1001@85.16.246.12>

...

;tag=rp27e1KSe969D To: <sip:1000@85.16.245.228:1024;line=5jkc7koy>;tag=a7mygnrad4 Call-ID: c93948e5-3a69-122d-94bc-00144fe6907a CSeq: 122030266 INVITE Contact: <sip:1000@85.16.245.228:1024;line=5jkc7koy>;reg-id=1 Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO, UPDATE Allow-Events: talk, hold, refer, call-info Content-Length: 0

SIP/2.0 200 Ok 23/Oct/2009-13:26:47.361

SIP/2.0 200 Ok Via: SIP/2.0/UDP 85.16.246.12:5061;rport=5061;branch=z9hG4bK86rt91cpH1FBF From: "1001 an PBX1" <sip:1001@85.16.246.12 <sip%3A1001@85.16.246.12>

...

;tag=rp27e1KSe969D To: <sip:1000@85.16.245.228:1024;line=5jkc7koy>;tag=a7mygnrad4 Call-ID: c93948e5-3a69-122d-94bc-00144fe6907a CSeq: 122030266 INVITE Contact: <sip:1000@85.16.245.228:1024;line=5jkc7koy>;reg-id=1 User-Agent: snom820/LAB_HD_HEAD_11_09_09_11_20 Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO, UPDATE Allow-Events: talk, hold, refer, call-info Supported: timer, 100rel, replaces, from-change Content-Type: application/sdp Content-Length: 244

v=0 o=root 353690302 353690303 IN IP4 85.16.245.228 s=call c=IN IP4 85.16.245.228 t=0 0 m=audio 60878 RTP/AVP 9 8 101 a=rtpmap:9 g722/8000 a=rtpmap:8 pcma/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:10 a=sendrecv

ACK sip:1000@85.16.245.228:1024;line=5jkc7koy SIP/2.0 23/Oct/2009-13:26:47.362

ACK sip:1000@85.16.245.228:1024;line=5jkc7koy SIP/2.0 Via: SIP/2.0/UDP 85.16.246.12:5061;rport;branch=z9hG4bK9FjKBXXSea6Xa Max-Forwards: 70 From: "1001 an PBX1" <sip:1001@85.16.246.12 <sip%3A1001@85.16.246.12>

...

;tag=rp27e1KSe969D To: <sip:1000@85.16.245.228:1024;line=5jkc7koy>;tag=a7mygnrad4 Call-ID: c93948e5-3a69-122d-94bc-00144fe6907a CSeq: 122030266 ACK Contact: <sip:mod_sofia@85.16.246.12:5061> Content-Length: 0

INFO sip:1000@85.16.245.228:1024;line=5jkc7koy SIP/2.0 23/Oct/2009-13:26:47.367

INFO sip:1000@85.16.245.228:1024;line=5jkc7koy SIP/2.0 Via: SIP/2.0/UDP 85.16.246.12:5061;rport;branch=z9hG4bKaSBcDreXBKvgp Max-Forwards: 70 From: "1001 an PBX1" <sip:1001@85.16.246.12 <sip%3A1001@85.16.246.12>

...

;tag=rp27e1KSe969D To: <sip:1000@85.16.245.228:1024;line=5jkc7koy>;tag=a7mygnrad4 Call-ID: c93948e5-3a69-122d-94bc-00144fe6907a CSeq: 122030267 INFO Contact: <sip:mod_sofia@85.16.246.12:5061> User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15203M Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, precondition, path, replaces Content-Type: message/sipfrag Content-Length: 24

From: To: "hubu" 1000

SIP/2.0 200 Ok 23/Oct/2009-13:26:47.766

SIP/2.0 200 Ok Via: SIP/2.0/UDP 85.16.246.12:5061;rport=5061;branch=z9hG4bKaSBcDreXBKvgp From: "1001 an PBX1" <sip:1001@85.16.246.12 <sip%3A1001@85.16.246.12>

...

;tag=rp27e1KSe969D To: <sip:1000@85.16.245.228:1024;line=5jkc7koy>;tag=a7mygnrad4 Call-ID: c93948e5-3a69-122d-94bc-00144fe6907a CSeq: 122030267 INFO Contact: <sip:1000@85.16.245.228:1024;line=5jkc7koy>;reg-id=1 Content-Length: 0

INFO sip:1000@85.16.245.228:1024;line=5jkc7koy SIP/2.0 23/Oct/2009-13:26:47.766

INFO sip:1000@85.16.245.228:1024;line=5jkc7koy SIP/2.0 Via: SIP/2.0/UDP 85.16.246.12:5061;rport;branch=z9hG4bKcByXgeg4548ND Max-Forwards: 70 From: "1001 an PBX1" <sip:1001@85.16.246.12 <sip%3A1001@85.16.246.12>

...

;tag=rp27e1KSe969D To: <sip:1000@85.16.245.228:1024;line=5jkc7koy>;tag=a7mygnrad4 Call-ID: c93948e5-3a69-122d-94bc-00144fe6907a CSeq: 122030268 INFO Contact: <sip:mod_sofia@85.16.246.12:5061> User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15203M Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, precondition, path, replaces Content-Type: message/sipfrag Content-Length: 32

From: To: "1001 an PBX1" 1001

SIP/2.0 200 Ok 23/Oct/2009-13:26:47.860

SIP/2.0 200 Ok Via: SIP/2.0/UDP 85.16.246.12:5061;rport=5061;branch=z9hG4bKcByXgeg4548ND From: "1001 an PBX1" <sip:1001@85.16.246.12 <sip%3A1001@85.16.246.12>

...

;tag=rp27e1KSe969D To: <sip:1000@85.16.245.228:1024;line=5jkc7koy>;tag=a7mygnrad4 Call-ID: c93948e5-3a69-122d-94bc-00144fe6907a CSeq: 122030268 INFO Contact: <sip:1000@85.16.245.228:1024;line=5jkc7koy>;reg-id=1 Content-Length: 0

BYE sip:mod_sofia@85.16.246.12:5061 SIP/2.0 23/Oct/2009-13:26:49.3

BYE sip:mod_sofia@85.16.246.12:5061 SIP/2.0 Via: SIP/2.0/UDP 85.16.245.228:1024;branch=z9hG4bK-susgzpvc38s7;rport From: <sip:1000@85.16.245.228:1024;line=5jkc7koy>;tag=a7mygnrad4 To: "1001 an PBX1" <sip:1001@85.16.246.12 <sip%3A1001@85.16.246.12>

...

;tag=rp27e1KSe969D Call-ID: c93948e5-3a69-122d-94bc-00144fe6907a CSeq: 1 BYE Max-Forwards: 70 Contact: <sip:1000@85.16.245.228:1024;line=5jkc7koy>;reg-id=1 User-Agent: snom820/LAB_HD_HEAD_11_09_09_11_20 RTP-RxStat: Total_Rx_Pkts=0,Rx_Pkts=69,Rx_Pkts_Lost=1,Remote_Rx_Pkts_Lost=0 RTP-TxStat: Total_Tx_Pkts=70,Tx_Pkts=65,Remote_Tx_Pkts=0 Content-Length: 0

SIP/2.0 200 OK 23/Oct/2009-13:26:49.4

SIP/2.0 200 OK Via: SIP/2.0/UDP 85.16.245.228:1024;branch=z9hG4bK-susgzpvc38s7;rport=1024 From: <sip:1000@85.16.245.228:1024;line=5jkc7koy>;tag=a7mygnrad4 To: "1001 an PBX1" <sip:1001@85.16.246.12 <sip%3A1001@85.16.246.12>

...

;tag=rp27e1KSe969D Call-ID: c93948e5-3a69-122d-94bc-00144fe6907a CSeq: 1 BYE User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15203M Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, precondition, path, replaces Content-Length: 0

On 22.10.2009 19:08, Anthony Minessale wrote:

...

I'm sure that problem is gone in svn trunk.

On Thu, Oct 22, 2009 at 11:25 AM, Brian West <brian@freeswitch.org <mailto:brian@freeswitch.org>> wrote:

I can't get what exactly you re talking about. Can you clarify ? Also please include the packets of interest only not the full trace if its not relevant to the bug.

/b

On Oct 22, 2009, at 10:44 AM, Helmut Kuper wrote:

> -----BEGIN PGP SIGNED MESSAGE----- > Hash: SHA1 > > Hi Mike, > > here it is: > > > Dialplan: > > > <extension name="Local_Extension"> > <condition field="destination_number" expression="^(10[01][0-9]) > $"> > <action application="set" data="dialed_extension=$1"/> > <action application="export" data="dialed_extension=$1"/> > <action application="set" data="transfer_ringback=$$ > {hold_music}"/> > <action application="set" data="hangup_after_bridge=true"/> > <action application="bridge" > data="user/${dialed_extension}@${domain_name}"/> > </condition> > </extension> >

_______________________________________________ FreeSWITCH-users mailing list FreeSWITCH-users@lists.freeswitch.org <mailto: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> <mailto:MSN%3Aanthony_minessale@hotmail.com<MSN%253Aanthony_minessale@hotmail.com>

GTALK/JABBER/PAYPAL:anthony.minessale@gmail.com<PAYPAL%3Aanthony.minessale@gmail.com> <mailto:PAYPAL%3Aanthony.minessale@gmail.com<PAYPAL%253Aanthony.minessale@gmail.com>

IRC: irc.freenode.net <http://irc.freenode.net> #freeswitch

FreeSWITCH Developer Conference sip:888@conference.freeswitch.org <sip%3A888@conference.freeswitch.org> <mailto:sip%3A888@conference.freeswitch.org<sip%253A888@conference.freeswitch.org>

iax:guest@conference.freeswitch.org/888 <http://iax:guest@conference.freeswitch.org/888> googletalk:conf+888@conference.freeswitch.org<googletalk%3Aconf%2B888@conference.freeswitch.org> <mailto:googletalk%3Aconf%2B888@conference.freeswitch.org<googletalk%253Aconf%252B888@conference.freeswitch.org>

pstn:213-799-1400

_______________________________________________ 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

- --

Mit freundlichen Grüßen Helmut Kuper Geschäftseinheit FD - Lösungen für Finanzdienstleister Telefax: (0441) 8000-2799 mailto:helmut.kuper@ewetel.de ___________________________________ EWE TEL GmbH Cloppenburger Straße 310 26133 Oldenburg EWE TEL GmbH

Handelsregister Amtsgericht Oldenburg HRB 3723 Vorsitzender des Aufsichtsrates: Heiko Harms Geschäftsführung: Hans-Joachim Iken (Vorsitzender), Ulf Heggenberger, Dr. Norbert Schulz, Dirk Thole Homepage: http://www.ewetel.de ___________________________________ -----BEGIN PGP SIGNATURE----- Version: GnuPG v1.4.7 (MingW32)

iD8DBQFK4ZUZ4tZeNddg3dwRAr+lAJ9b3geDcwT0bkz++YG9Sjq62AtHBQCfWALu jkUQIoNN6CYJFp10ebA5MIU= =cHej -----END PGP SIGNATURE-----

_______________________________________________ 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> iax:guest@conference.freeswitch.org/888 googletalk:conf+888@conference.freeswitch.org<googletalk%3Aconf%2B888@conference.freeswitch.org> pstn:213-799-1400

Could you maybe consolidate all of your problems into 1 thread. I am getting dizzy. You have 2 on the same subject and you say it works on one and does not on the other. Last week we tested all of this with latest trunk and there is no longer any problems of any sort with the display related stuff. On Mon, Oct 26, 2009 at 6:00 AM, Helmut Kuper <helmut.kuper@ewetel.de>wrote:

-----BEGIN PGP SIGNED MESSAGE----- Hash: SHA1

Hi Anthony,

hm, no not really. There is no change in this behaviour in "FreeSWITCH Version 1.0.trunk (15225M)"

There is still a caller name change in callee's display.

I'm not sure who is wrong here. Either FS or Snom ...

regards Helmut

On 23.10.2009 17:51, Anthony Minessale wrote:

...

should be even better in 15210

-----BEGIN PGP SIGNATURE----- Version: GnuPG v1.4.7 (MingW32)

iD8DBQFK5YE94tZeNddg3dwRAigrAJ0chk/kiDQo2Z6yFbjpJovmPor46ACfValq u9RajDfF0rNdzkaUOVjULmk= =697e -----END PGP SIGNATURE-----

_______________________________________________ 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> iax:guest@conference.freeswitch.org/888 googletalk:conf+888@conference.freeswitch.org<googletalk%3Aconf%2B888@conference.freeswitch.org> pstn:213-799-1400

depending on your dialplan every time you bridge to a channel it changes the display to match who you are talking to. if you tried to set it with the variable then you call someone that is going to cause this. Take away the display app and/or any special variables and let it naturally work. On Mon, Oct 26, 2009 at 10:51 AM, Helmut Kuper <helmut.kuper@ewetel.de>wrote:

-----BEGIN PGP SIGNED MESSAGE----- Hash: SHA1

Hi Anthony,

sorry for making you dizzy ... bun in fact in my point of view I have two different problems.

1. One concerns the way using send_display in pre_answer mode. Simply to send error texts to caller's display. This works again with latest trunk.

2. The other one (this thread) concerns the (in my eyes newly introduced) two INFO messages which FS sends to callee after callee picked up his phone. The first INFO switches callee's display to a name set in "originaton_callee_id_name" (a) and immediately after that the second INFO switches it back to callee's real name (b). You can see this in display only if (a) and (b) are not the same.

I used Snom 370 phones with FW 8.2.16 as caller and callee. I did an internal call (sip to sip).

Maybe this is the same code problem, but on my level they are two different problems, so sorry for confusing you. I hope this clears things up.

On 26.10.2009 15:41, Anthony Minessale wrote:

...

Could you maybe consolidate all of your problems into 1 thread. I am getting dizzy. You have 2 on the same subject and you say it works on one and does not on the other.

Last week we tested all of this with latest trunk and there is no longer any problems of any sort with the display related stuff.

-----BEGIN PGP SIGNATURE----- Version: GnuPG v1.4.7 (MingW32)

iD8DBQFK5cVz4tZeNddg3dwRAt2+AJsEQuRTEhE74+XvciTXOC0+kr0tbwCfRzUf fgVIZwAV/IthjWwvXzRO3TA= =n7Sr -----END PGP SIGNATURE-----

_______________________________________________ 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> iax:guest@conference.freeswitch.org/888 googletalk:conf+888@conference.freeswitch.org<googletalk%3Aconf%2B888@conference.freeswitch.org> pstn:213-799-1400

On Tue, Oct 20, 2009 at 12:25 AM, Helmut Kuper <helmut.kuper@ewetel.de>wrote:

-----BEGIN PGP SIGNED MESSAGE----- Hash: SHA1

Hello Anthony,

On 19.10.2009 22:07, Anthony Minessale wrote:

...

please update and test trunk

1) I changed the core to remove the excess data by default in your scenario 2) I added variables you can use to control it origination_callee_id_name origination_callee_id_number which belong in {} in the dial string eg {origination_callee_id_number=1234}openzap/1/a/1234

updated and testet for SIP calls. "origination_callee_id_number=1234" works within the dial string and via using "export" application but not via "set" application. Didn't test it for openzap, yet but I guess it will work, too.

Thanks for testing. BTW, according to Tony's instructions the user needs to put the variable definition inside the {} of the dialstring. This means that the vars are designed for the new leg, not the local leg, which means that "export" would work but "set" would not. (Remember, "set" is to set a chan var on the local channel, "export" will set a chan var locally *and* on the other call leg. See also the "nolocal" option of export.)

What left is that "unknown" thing on callee's display (SIP phones only I guess) ... I would suggest to make sending "unknown" INFO message optional, but to be honest, I have no idea for what it was invented, so maybe my suggestion is nonsens.

Under what conditions did you see "unknown"? I'm wondering if the user can just pick a default other than "unknown" if he wants something else to be displayed. Thoughts? -MC

-----BEGIN PGP SIGNED MESSAGE----- Hash: SHA1 Hi Brian, hi Mike, On 20.10.2009 18:41, Brian West wrote:

Or just set the var to what you want it to say?

Yes, understood and it works so far. This means that I must enhance my dialplan to set this new variable to preserve old behaviour. No big deal, but at least I have to know it.

/b

On Oct 20, 2009, at 11:19 AM, Michael Collins wrote:

...

Under what conditions did you see "unknown"? I'm wondering if the user can just pick a default other than "unknown" if he wants something else to be displayed.

I get it for internal calls from Snom to Snom. It seems to be the default configuration. The sip flow shows two INFO messages sent from FS to caller after callee picked up. The first INFO messages set the callee's name to "unknown" on caller's side. The second changed it back to callee's number. Maybe there is a plan behind it ... by now it is simply increasing the sip signalling load. Any ideas for what the first INFO message is? regards helmut -----BEGIN PGP SIGNATURE----- Version: GnuPG v1.4.7 (MingW32) iD8DBQFK3sXF4tZeNddg3dwRAshzAJ99Jsp/RNtndeulae80pvHPqC9YHACghFxT y0JZzsSKrGyPXTnPypy+qqQ= =jNtK -----END PGP SIGNATURE-----