VoIP Mailing List Archives :: View topic - [Freeswitch-users] Qustion about INFO messages after Connect/Answer

Sponsor: VoiceMeUp - Corporate & Wholesale VoIP Services

VoIP Mailing List Archives
Mailing list archives for the VoIP community

[Freeswitch-users] Qustion about INFO messages after Connect/Answer

VoIP Mailing List Archives Forum Index -> freeSWITCH Users

View previous topic :: View next topic

Author

Message

helmut.kuper at ewetel.de
Guest

Posted: Mon Oct 19, 2009 10:05 am Post subject: [Freeswitch-users] Qustion about INFO messages after Connect

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

Hello Anthony,

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

Quote:

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:

Quote:

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
-----BEGIN PGP SIGNATURE-----
Version: GnuPG v1.4.7 (MingW32)

iD8DBQFK3HwJ4tZeNddg3dwRAieqAKDCn47dzctaYpweRGzovbMPcKD5KQCgjNx+
nNTz4r7+N7mI3Wj4GayFdTk=
=MYOb
-----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

helmut.kuper at ewetel.de
Guest

Posted: Mon Oct 19, 2009 10:47 am Post subject: [Freeswitch-users] Qustion about INFO messages after Connect

-----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:

Quote:

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-----

_______________________________________________
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

helmut.kuper at ewetel.de
Guest

Posted: Tue Oct 20, 2009 2:36 am Post subject: [Freeswitch-users] Qustion about INFO messages after Connect

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

Hello Anthony,

On 19.10.2009 22:07, Anthony Minessale wrote:

Quote:

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.

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.

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

iD8DBQFK3WXZ4tZeNddg3dwRAs9eAJ4+ZtjNYmt/U9fK3o2LnsO7Ztf/ygCgp+c4
eZWafXUZn3LjC07q/1IcsvM=
=7N8O
-----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

helmut.kuper at ewetel.de
Guest

Posted: Wed Oct 21, 2009 3:37 am Post subject: [Freeswitch-users] Qustion about INFO messages after Connect

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

Hi Brian, hi Mike,

On 20.10.2009 18:41, Brian West wrote:

Quote:

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.

Quote:

/b

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

Quote:

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-----

_______________________________________________
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

helmut.kuper at ewetel.de
Guest

Posted: Wed Oct 21, 2009 12:51 pm Post subject: [Freeswitch-users] Qustion about INFO messages after Connect

-----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 Smile

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:

Quote:

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-----

_______________________________________________
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

helmut.kuper at ewetel.de
Guest

Posted: Thu Oct 22, 2009 10:55 am Post subject: [Freeswitch-users] Qustion about INFO messages after Connect

-----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:

Quote:

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-----

_______________________________________________
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

helmut.kuper at ewetel.de
Guest

Posted: Fri Oct 23, 2009 6:44 am Post subject: [Freeswitch-users] Qustion about INFO messages after Connect

-----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>;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>;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>;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>;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>;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>;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>;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>;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>;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>;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>;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>;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>;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:

Quote:

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:

Quote:

_______________________________________________
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
<mailto:MSN%3Aanthony_minessale@hotmail.com>
GTALK/JABBER/PAYPAL:anthony.minessale@gmail.com
<mailto:PAYPAL%3Aanthony.minessale@gmail.com>
IRC: irc.freenode.net <http://irc.freenode.net> #freeswitch

FreeSWITCH Developer Conference
sip:888@conference.freeswitch.org
<mailto:sip%3A888@conference.freeswitch.org>
iax:guest@conference.freeswitch.org/888
<http://iax:guest@conference.freeswitch.org/888>
googletalk:conf+888@conference.freeswitch.org
<mailto:googletalk%3Aconf%2B888@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

helmut.kuper at ewetel.de
Guest

Posted: Mon Oct 26, 2009 6:10 am Post subject: [Freeswitch-users] Qustion about INFO messages after Connect

-----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:

Quote:

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

helmut.kuper at ewetel.de
Guest

Posted: Mon Oct 26, 2009 11:09 am Post subject: [Freeswitch-users] Qustion about INFO messages after Connect

-----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:

Quote:

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

Display posts from previous:

	VoIP Mailing List Archives Forum Index -> freeSWITCH Users	All times are GMT - 5 Hours
Page 1 of 1

You cannot post new topics in this forum
You cannot reply to topics in this forum
You cannot edit your posts in this forum
You cannot delete your posts in this forum
You cannot vote in polls in this forum