[Freeswitch-users] effective_caller_id_name &effective_caller_id_number - not working
Lloyd Aloysius
lloyd.aloysius at sunteltech.ca
Wed Jul 3 09:27:43 MSD 2013
The invite is below. Why From missing the Caller ID Number(*9052066900)*.
But the remote party id show correctly. I have the caller-id-in-from=true
parameter in the gateway. Any help is appreciated.
INVITE sip:14164790606 at sip05.unlimitel.ca SIP/2.0
Via: SIP/2.0/UDP 1.2.3.4:5080;rport;branch=z9hG4bK627cSmy3c7Dyp
Max-Forwards: 69
*From: "Alcan Cable" <sip:barbara at alcan.mydomain.ca>;tag=e2U2v28yHXr1K*
To: <sip:14164790606 at sip05.unlimitel.ca>
Call-ID: 5ab79312-5d6a-1231-27b4-000c2974feab
CSeq: 46031801 INVITE
Contact: <sip:gw+utel at 1.2.3.4:5080;transport=udp;gw=utel>
User-Agent: FreeSWITCH-mod_sofia/1.5.3b+git~20130628T160527Z~a52a604fbb
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER,
REFER, NOTIFY
Supported: timer, precondition, path, replaces
Allow-Events: talk, hold, conference, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 207
X-FS-Support: update_display,send_info
*Remote-Party-ID: "Alcan Cable" <sip:9052066900 at alcan.mydomain.ca
>;party=calling;screen=yes;privacy=off*
v=0
o=FreeSWITCH 1372709880 1372709881 IN IP4 1.2.3.4
s=FreeSWITCH
c=IN IP4 1.2.3.4
t=0 0
m=audio 25850 RTP/AVP 0 8 3 101 13
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
Lloyd
On Tue, Jul 2, 2013 at 3:49 AM, Avi Marcus <avi at avimarcus.net> wrote:
> OK, where's the siptrace?
> The original one says the call is to 4164750401 but I don't see that in
> the fs_cli log...
> -Avi
>
>
> On Tue, Jul 2, 2013 at 5:18 AM, Lloyd Aloysius <lloyd.aloysius at gmail.com>wrote:
>
>> Please have a look into the freeswitch log below.
>>
>> http://pastebin.freeswitch.org/21140
>>
>> Thanks
>> Lloyd
>>
>>
>> *
>> *
>> * <http://www.sunteltech.ca/blog/>*
>>
>>
>> On Mon, Jul 1, 2013 at 9:58 PM, Michael Collins <msc at freeswitch.org>wrote:
>>
>>> Please put this into pastebin.freeswitch.org using FreeSWITCH Log as
>>> the syntax highlighting. Then reply to this thread with the pastebin URL.
>>>
>>> -MC
>>>
>>>
>>> On Mon, Jul 1, 2013 at 5:40 PM, Lloyd Aloysius <lloyd.aloysius at gmail.com
>>> > wrote:
>>>
>>>> fs_cli log
>>>>
>>>>
>>>> 2013-07-01 20:37:32.446979 [INFO] mod_dialplan_xml.c:558 Processing
>>>> Barbara Ladner <barbara>->4164790606 in context alcan.mydomain.ca
>>>> Dialplan: sofia/sipinterface_1/barbara at alcan.mydomain.ca parsing
>>>> [alcan.mydomain.ca->outbound_dialplan.] continue=false
>>>> Dialplan: sofia/sipinterface_1/barbara at alcan.mydomain.ca Regex (PASS)
>>>> [outbound_dialplan.] destination_number(4164790606) =~ /^(4164790606)$/
>>>> break=on-false
>>>> Dialplan: sofia/sipinterface_1/barbara at alcan.mydomain.ca Action
>>>> lua(/usr/local/hostedsip/luascripts/outbound_dialplan.lua)
>>>> 2013-07-01 20:37:32.457029 [DEBUG] switch_core_state_machine.c:167
>>>> (sofia/sipinterface_1/barbara at alcan.mydomain.ca) State Change
>>>> CS_ROUTING -> CS_EXECUTE
>>>> 2013-07-01 20:37:32.457029 [DEBUG] switch_core_session.c:1341 Send
>>>> signal sofia/sipinterface_1/barbara at alcan.mydomain.ca [BREAK]
>>>> 2013-07-01 20:37:32.457029 [DEBUG] switch_core_state_machine.c:471
>>>> (sofia/sipinterface_1/barbara at alcan.mydomain.ca) State ROUTING going
>>>> to sleep
>>>> 2013-07-01 20:37:32.457029 [DEBUG] switch_core_state_machine.c:416
>>>> (sofia/sipinterface_1/barbara at alcan.mydomain.ca) Running State Change
>>>> CS_EXECUTE
>>>> 2013-07-01 20:37:32.457029 [DEBUG] switch_core_state_machine.c:478
>>>> (sofia/sipinterface_1/barbara at alcan.mydomain.ca) State EXECUTE
>>>> 2013-07-01 20:37:32.457029 [DEBUG] mod_sofia.c:230 sofia/sipinterface_1/
>>>> barbara at alcan.mydomain.ca SOFIA EXECUTE
>>>> 2013-07-01 20:37:32.457029 [DEBUG] switch_core_state_machine.c:209
>>>> sofia/sipinterface_1/barbara at alcan.mydomain.ca Standard EXECUTE
>>>> EXECUTE sofia/sipinterface_1/barbara at alcan.mydomain.calua(/usr/local/hostedsip/luascripts/outbound_dialplan.lua)
>>>> 2013-07-01 20:37:32.468008 [INFO] switch_cpp.cpp:651 Sending early media
>>>> 2013-07-01 20:37:32.468008 [DEBUG] switch_core_media.c:3982 AUDIO RTP
>>>> [sofia/sipinterface_1/barbara at alcan.mydomain.ca] A.B.C.D port 24402 ->
>>>> 192.168.240.105 port 3000 codec: 0 ms: 20
>>>> 2013-07-01 20:37:32.468008 [DEBUG] switch_rtp.c:2767 Starting timer
>>>> [soft] 160 bytes per 20ms
>>>> 2013-07-01 20:37:32.468008 [DEBUG] switch_core_media.c:4313 Set 2833
>>>> dtmf send payload to 101
>>>> 2013-07-01 20:37:32.468008 [DEBUG] switch_core_media.c:4319 Set 2833
>>>> dtmf receive payload to 101
>>>> 2013-07-01 20:37:32.468008 [DEBUG] mod_sofia.c:2105 Ring SDP:
>>>> v=0
>>>> o=FreeSWITCH 1372701050 1372701051 IN IP4 A.B.C.D
>>>> s=FreeSWITCH
>>>> c=IN IP4 A.B.C.D
>>>> t=0 0
>>>> m=audio 24402 RTP/AVP 0 101
>>>> a=rtpmap:0 PCMU/8000
>>>> a=rtpmap:101 telephone-event/8000
>>>> a=fmtp:101 0-16
>>>> a=silenceSupp:off - - - -
>>>> a=ptime:20
>>>> a=sendrecv
>>>>
>>>> 2013-07-01 20:37:32.468008 [NOTICE] mod_sofia.c:2108 Pre-Answer
>>>> sofia/sipinterface_1/barbara at alcan.mydomain.ca!
>>>> 2013-07-01 20:37:32.468008 [DEBUG] switch_channel.c:3316
>>>> (sofia/sipinterface_1/barbara at alcan.mydomain.ca) Callstate Change
>>>> RINGING -> EARLY
>>>> 2013-07-01 20:37:32.468008 [DEBUG] switch_core_session.c:861 Send
>>>> signal sofia/sipinterface_1/barbara at alcan.mydomain.ca [BREAK]
>>>> 2013-07-01 20:37:32.468008 [DEBUG] switch_core_session.c:1006 Send
>>>> signal sofia/sipinterface_1/barbara at alcan.mydomain.ca [BREAK]
>>>> 2013-07-01 20:37:32.476978 [DEBUG] sofia.c:5764 Channel
>>>> sofia/sipinterface_1/barbara at alcan.mydomain.ca entering state
>>>> [early][183]
>>>> 2013-07-01 20:37:32.476978 [DEBUG] mod_sofia.c:822 Local SDP
>>>> sofia/sipinterface_1/barbara at alcan.mydomain.ca:
>>>> v=0
>>>> o=FreeSWITCH 1372701050 1372701052 IN IP4 A.B.C.D
>>>> s=FreeSWITCH
>>>> c=IN IP4 A.B.C.D
>>>> t=0 0
>>>> m=audio 24402 RTP/AVP 0 101
>>>> a=rtpmap:0 PCMU/8000
>>>> a=rtpmap:101 telephone-event/8000
>>>> a=fmtp:101 0-16
>>>> a=silenceSupp:off - - - -
>>>> a=ptime:20
>>>> a=sendrecv
>>>>
>>>> 2013-07-01 20:37:32.476978 [DEBUG] switch_core_session.c:861 Send
>>>> signal sofia/sipinterface_1/barbara at alcan.mydomain.ca [BREAK]
>>>> 2013-07-01 20:37:32.476978 [NOTICE] switch_cpp.cpp:633 Channel
>>>> [sofia/sipinterface_1/barbara at alcan.mydomain.ca] has been answered
>>>> 2013-07-01 20:37:32.476978 [DEBUG] switch_channel.c:3595
>>>> (sofia/sipinterface_1/barbara at alcan.mydomain.ca) Callstate Change
>>>> EARLY -> ACTIVE
>>>> 2013-07-01 20:37:32.476978 [INFO] switch_cpp.cpp:1287
>>>> [outbound_dialplan] domain_name: alcan.mydomain.ca
>>>> 2013-07-01 20:37:32.476978 [INFO] switch_cpp.cpp:1287
>>>> [outbound_dialplan] context: alcan.mydomain.ca
>>>> 2013-07-01 20:37:32.476978 [INFO] switch_cpp.cpp:1287
>>>> [outbound_dialplan] destination number: 4164790606
>>>> 2013-07-01 20:37:32.476978 [INFO] switch_cpp.cpp:1287
>>>> [outbound_dialplan] sip_from_uri: barbara at alcan.mydomain.ca
>>>> 2013-07-01 20:37:32.476978 [DEBUG] switch_core_session.c:1006 Send
>>>> signal sofia/sipinterface_1/barbara at alcan.mydomain.ca [BREAK]
>>>> 2013-07-01 20:37:32.476978 [DEBUG] freeswitch_lua.cpp:352 DBH handle
>>>> 0x7fdcb8197170 Connected.
>>>> EXECUTE sofia/sipinterface_1/barbara at alcan.mydomain.caexport(nolocal:domain_name=
>>>> alcan.mydomain.ca)
>>>> 2013-07-01 20:37:32.476978 [DEBUG] sofia.c:5764 Channel
>>>> sofia/sipinterface_1/barbara at alcan.mydomain.ca entering state
>>>> [completed][200]
>>>> 2013-07-01 20:37:32.476978 [DEBUG] switch_channel.c:1222 EXPORT
>>>> (export_vars) (REMOTE ONLY) [domain_name]=[alcan.mydomain.ca]
>>>> EXECUTE sofia/sipinterface_1/barbara at alcan.mydomain.caexport(nolocal:sip_from_uri=
>>>> barbara at alcan.mydomain.ca)
>>>> 2013-07-01 20:37:32.476978 [DEBUG] switch_channel.c:1222 EXPORT
>>>> (export_vars) (REMOTE ONLY) [sip_from_uri]=[barbara at alcan.mydomain.ca]
>>>> EXECUTE sofia/sipinterface_1/barbara at alcan.mydomain.caexport(nolocal:execute_on_answer=lua
>>>> /usr/local/hostedsip/luascripts/call_recording.lua)
>>>> 2013-07-01 20:37:32.476978 [DEBUG] switch_channel.c:1222 EXPORT
>>>> (export_vars) (REMOTE ONLY) [execute_on_answer]=[lua
>>>> /usr/local/hostedsip/luascripts/call_recording.lua]
>>>> 2013-07-01 20:37:32.476978 [INFO] switch_cpp.cpp:1287
>>>> [outbound_dialplan] begin bridge the destianation number
>>>> 2013-07-01 20:37:32.476978 [INFO] switch_cpp.cpp:1287
>>>> [outbound_dialplan] user_query: select
>>>> pstn_callerid,caller_id_name,caller_id_number,block_caller_id_status from
>>>> user where sip_address='barbara at alcan.mydomain.ca'
>>>> 2013-07-01 20:37:32.486975 [INFO] switch_cpp.cpp:1287
>>>> [outbound_dialplan] outbound call originate from a USER
>>>> 2013-07-01 20:37:32.486975 [INFO] switch_cpp.cpp:1287
>>>> [outbound_dialplan] pstn_callerid: USE_PHONE_SETTINGS
>>>> 2013-07-01 20:37:32.486975 [INFO] switch_cpp.cpp:1287
>>>> [outbound_dialplan] caller_id_name:
>>>> 2013-07-01 20:37:32.486975 [INFO] switch_cpp.cpp:1287
>>>> [outbound_dialplan] caller_id_number:
>>>> 2013-07-01 20:37:32.486975 [INFO] switch_cpp.cpp:1287
>>>> [outbound_dialplan] block_caller_id_status: false
>>>> 2013-07-01 20:37:32.486975 [INFO] switch_cpp.cpp:1287
>>>> [outbound_dialplan] local_stream_conf_id1
>>>> 2013-07-01 20:37:32.486975 [INFO] switch_cpp.cpp:1287
>>>> [outbound_dialplan] hold_music: local_stream://default
>>>> EXECUTE sofia/sipinterface_1/barbara at alcan.mydomain.caset(hold_music=local_stream://default)
>>>> 2013-07-01 20:37:32.486975 [DEBUG] mod_dptools.c:1373
>>>> sofia/sipinterface_1/barbara at alcan.mydomain.ca SET
>>>> [hold_music]=[local_stream://default]
>>>> 2013-07-01 20:37:32.486975 [INFO] switch_cpp.cpp:1287
>>>> [outbound_dialplan] customer_query : select caller_id_name,caller_id_number
>>>> from customer where domain='alcan.mydomain.ca'
>>>> 2013-07-01 20:37:32.486975 [INFO] switch_cpp.cpp:1287
>>>> [outbound_dialplan] caller_id_name: Alcan Cable
>>>> 2013-07-01 20:37:32.486975 [INFO] switch_cpp.cpp:1287
>>>> [outbound_dialplan] caller_id_number: 9052066900
>>>> 2013-07-01 20:37:32.486975 [INFO] switch_cpp.cpp:1287
>>>> [outbound_dialplan] outbound_caller_id_name & outbound_caller_id_number
>>>> 2013-07-01 20:37:32.486975 [INFO] switch_cpp.cpp:1287
>>>> [outbound_dialplan] outbound_caller_id_name: Alcan Cable
>>>> 2013-07-01 20:37:32.486975 [INFO] switch_cpp.cpp:1287
>>>> [outbound_dialplan] outbound_caller_id_number: 9052066900
>>>> EXECUTE sofia/sipinterface_1/barbara at alcan.mydomain.caset(effective_caller_id_name=Alcan Cable)
>>>> 2013-07-01 20:37:32.486975 [DEBUG] mod_dptools.c:1373
>>>> sofia/sipinterface_1/barbara at alcan.mydomain.ca SET
>>>> [effective_caller_id_name]=[Alcan Cable]
>>>> EXECUTE sofia/sipinterface_1/barbara at alcan.mydomain.caset(effective_caller_id_number=
>>>> 9052066900)
>>>> 2013-07-01 20:37:32.486975 [DEBUG] mod_dptools.c:1373
>>>> sofia/sipinterface_1/barbara at alcan.mydomain.ca SET
>>>> [effective_caller_id_number]=[9052066900]
>>>> 2013-07-01 20:37:32.486975 [INFO] switch_cpp.cpp:1287
>>>> [gateway_routing.lua] domain_name: alcan.mydomain.ca
>>>> 2013-07-01 20:37:32.486975 [INFO] switch_cpp.cpp:1287
>>>> [gateway_routing.lua] context: alcan.mydomain.ca
>>>> 2013-07-01 20:37:32.486975 [INFO] switch_cpp.cpp:1287
>>>> [gateway_routing.lua] Outbound Maximum Calls Reached: db
>>>> alcan.mydomain.ca call_count 5 outbound.max.calls at alcan.mydomain.caXML
>>>> alcan.mydomain.ca
>>>> EXECUTE sofia/sipinterface_1/barbara at alcan.mydomain.ca limit(db
>>>> alcan.mydomain.ca call_count 5 outbound.max.calls at alcan.mydomain.caXML
>>>> alcan.mydomain.ca)
>>>> 2013-07-01 20:37:32.486975 [INFO] switch_limit.c:126 incr called:
>>>> alcan.mydomain.ca_call_count max:5, interval:0
>>>> 2013-07-01 20:37:32.486975 [INFO] mod_db.c:193 Usage for
>>>> alcan.mydomain.ca_call_count is now 1/5
>>>> 2013-07-01 20:37:32.536989 [INFO] switch_cpp.cpp:1287
>>>> [gateway_routing.lua] destination_number: 141
>>>> 2013-07-01 20:37:32.536989 [INFO] switch_cpp.cpp:1287
>>>> [gateway_routing.lua] gateway_1: utel
>>>> 2013-07-01 20:37:32.536989 [INFO] switch_cpp.cpp:1287
>>>> [gateway_routing.lua] gateway_2: voipms
>>>> 2013-07-01 20:37:32.536989 [INFO] switch_cpp.cpp:1287
>>>> [gateway_routing.lua] gateway_3: flowroute
>>>> 2013-07-01 20:37:32.536989 [INFO] switch_cpp.cpp:1287
>>>> [gateway_routing.lua] destination_number: 14164790606
>>>> 2013-07-01 20:37:32.536989 [INFO] switch_cpp.cpp:1287
>>>> [gateway_routing.lua] progress_timeout: 10
>>>> 2013-07-01 20:37:32.536989 [INFO] switch_cpp.cpp:1287
>>>> [gateway_routing.lua] gateway1: utel
>>>> 2013-07-01 20:37:32.536989 [INFO] switch_cpp.cpp:1287
>>>> [gateway_routing.lua] gateway2: voipms
>>>> 2013-07-01 20:37:32.536989 [INFO] switch_cpp.cpp:1287
>>>> [gateway_routing.lua] gateway3: flowroute
>>>> EXECUTE sofia/sipinterface_1/barbara at alcan.mydomain.caset(hangup_after_bridge=true)
>>>> 2013-07-01 20:37:32.536989 [DEBUG] mod_dptools.c:1373
>>>> sofia/sipinterface_1/barbara at alcan.mydomain.ca SET
>>>> [hangup_after_bridge]=[true]
>>>> EXECUTE sofia/sipinterface_1/barbara at alcan.mydomain.caset(continue_on_fail=true)
>>>> 2013-07-01 20:37:32.536989 [DEBUG] mod_dptools.c:1373
>>>> sofia/sipinterface_1/barbara at alcan.mydomain.ca SET
>>>> [continue_on_fail]=[true]
>>>> EXECUTE sofia/sipinterface_1/barbara at alcan.mydomain.caset(pstn_direction=PSTN_OUTBOUND)
>>>> 2013-07-01 20:37:32.536989 [DEBUG] mod_dptools.c:1373
>>>> sofia/sipinterface_1/barbara at alcan.mydomain.ca SET
>>>> [pstn_direction]=[PSTN_OUTBOUND]
>>>> EXECUTE sofia/sipinterface_1/barbara at alcan.mydomain.caexport(pstn_direction=PSTN_OUTBOUND)
>>>> 2013-07-01 20:37:32.536989 [DEBUG] switch_channel.c:1222 EXPORT
>>>> (export_vars) [pstn_direction]=[PSTN_OUTBOUND]
>>>> EXECUTE sofia/sipinterface_1/barbara at alcan.mydomain.caexport(nolocal:route_destination_number=
>>>> 14164790606)
>>>> 2013-07-01 20:37:32.536989 [DEBUG] switch_channel.c:1222 EXPORT
>>>> (export_vars) (REMOTE ONLY) [route_destination_number]=[14164790606]
>>>> EXECUTE sofia/sipinterface_1/barbara at alcan.mydomain.caexport(nolocal:route_prefix=1416)
>>>> 2013-07-01 20:37:32.536989 [DEBUG] switch_channel.c:1222 EXPORT
>>>> (export_vars) (REMOTE ONLY) [route_prefix]=[1416]
>>>> EXECUTE sofia/sipinterface_1/barbara at alcan.mydomain.caset(progress_timeout=10)
>>>> 2013-07-01 20:37:32.536989 [DEBUG] mod_dptools.c:1373
>>>> sofia/sipinterface_1/barbara at alcan.mydomain.ca SET
>>>> [progress_timeout]=[10]
>>>> EXECUTE sofia/sipinterface_1/barbara at alcan.mydomain.cabridge_export(nolocal:gateway=utel)
>>>> 2013-07-01 20:37:32.536989 [DEBUG] switch_channel.c:1222 EXPORT
>>>> (bridge_export_vars) (REMOTE ONLY) [gateway]=[utel]
>>>> EXECUTE sofia/sipinterface_1/barbara at alcan.mydomain.cabridge({sip_cid_type=none}sofia/gateway/utel/
>>>> 14164790606)
>>>> 2013-07-01 20:37:32.536989 [DEBUG] switch_channel.c:1176
>>>> sofia/sipinterface_1/barbara at alcan.mydomain.ca EXPORTING[export_vars]
>>>> [domain_name]=[alcan.mydomain.ca] to event
>>>> 2013-07-01 20:37:32.536989 [DEBUG] switch_channel.c:1176
>>>> sofia/sipinterface_1/barbara at alcan.mydomain.ca EXPORTING[export_vars]
>>>> [sip_from_uri]=[barbara at alcan.mydomain.ca] to event
>>>> 2013-07-01 20:37:32.536989 [DEBUG] switch_channel.c:1176
>>>> sofia/sipinterface_1/barbara at alcan.mydomain.ca EXPORTING[export_vars]
>>>> [execute_on_answer]=[lua
>>>> /usr/local/hostedsip/luascripts/call_recording.lua] to event
>>>> 2013-07-01 20:37:32.536989 [DEBUG] switch_channel.c:1176
>>>> sofia/sipinterface_1/barbara at alcan.mydomain.ca EXPORTING[export_vars]
>>>> [pstn_direction]=[PSTN_OUTBOUND] to event
>>>> 2013-07-01 20:37:32.536989 [DEBUG] switch_channel.c:1176
>>>> sofia/sipinterface_1/barbara at alcan.mydomain.ca EXPORTING[export_vars]
>>>> [route_destination_number]=[14164790606] to event
>>>> 2013-07-01 20:37:32.536989 [DEBUG] switch_channel.c:1176
>>>> sofia/sipinterface_1/barbara at alcan.mydomain.ca EXPORTING[export_vars]
>>>> [route_prefix]=[1416] to event
>>>> 2013-07-01 20:37:32.536989 [DEBUG] switch_ivr_originate.c:2050 Parsing
>>>> global variables
>>>> 2013-07-01 20:37:32.536989 [DEBUG] switch_event.c:1617 Parsing variable
>>>> [sip_cid_type]=[none]
>>>> 2013-07-01 20:37:32.536989 [NOTICE] switch_channel.c:1030 New Channel
>>>> sofia/sipinterface_2/14164790606 [9584d698-e2af-11e2-bd35-8118757a60c5]
>>>> 2013-07-01 20:37:32.536989 [DEBUG] mod_sofia.c:4420
>>>> (sofia/sipinterface_2/14164790606) State Change CS_NEW -> CS_INIT
>>>> 2013-07-01 20:37:32.536989 [DEBUG] switch_core_session.c:1341 Send
>>>> signal sofia/sipinterface_2/14164790606 [BREAK]
>>>> 2013-07-01 20:37:32.536989 [DEBUG] switch_core_state_machine.c:416
>>>> (sofia/sipinterface_2/14164790606) Running State Change CS_INIT
>>>> 2013-07-01 20:37:32.536989 [DEBUG] switch_core_state_machine.c:455
>>>> (sofia/sipinterface_2/14164790606) State INIT
>>>> 2013-07-01 20:37:32.536989 [DEBUG] mod_sofia.c:87
>>>> sofia/sipinterface_2/14164790606 SOFIA INIT
>>>> 2013-07-01 20:37:32.536989 [DEBUG] sofia_glue.c:1220 Local SDP:
>>>> v=0
>>>> o=FreeSWITCH 1372703258 1372703259 IN IP4 A.B.C.D
>>>> s=FreeSWITCH
>>>> c=IN IP4 A.B.C.D
>>>> t=0 0
>>>> m=audio 22194 RTP/AVP 0 8 3 101 13
>>>> a=rtpmap:101 telephone-event/8000
>>>> a=fmtp:101 0-16
>>>> a=ptime:20
>>>> a=sendrecv
>>>>
>>>> 2013-07-01 20:37:32.536989 [DEBUG] mod_sofia.c:114
>>>> (sofia/sipinterface_2/14164790606) State Change CS_INIT -> CS_ROUTING
>>>> 2013-07-01 20:37:32.536989 [DEBUG] switch_core_session.c:1341 Send
>>>> signal sofia/sipinterface_2/14164790606 [BREAK]
>>>> 2013-07-01 20:37:32.536989 [DEBUG] switch_core_state_machine.c:455
>>>> (sofia/sipinterface_2/14164790606) State INIT going to sleep
>>>> 2013-07-01 20:37:32.536989 [DEBUG] switch_core_state_machine.c:416
>>>> (sofia/sipinterface_2/14164790606) Running State Change CS_ROUTING
>>>> 2013-07-01 20:37:32.536989 [DEBUG] switch_core_state_machine.c:471
>>>> (sofia/sipinterface_2/14164790606) State ROUTING
>>>> 2013-07-01 20:37:32.536989 [DEBUG] mod_sofia.c:137
>>>> sofia/sipinterface_2/14164790606 SOFIA ROUTING
>>>> 2013-07-01 20:37:32.536989 [DEBUG] switch_ivr_originate.c:67
>>>> (sofia/sipinterface_2/14164790606) State Change CS_ROUTING ->
>>>> CS_CONSUME_MEDIA
>>>> 2013-07-01 20:37:32.536989 [DEBUG] switch_core_session.c:1341 Send
>>>> signal sofia/sipinterface_2/14164790606 [BREAK]
>>>> 2013-07-01 20:37:32.536989 [DEBUG] switch_core_state_machine.c:471
>>>> (sofia/sipinterface_2/14164790606) State ROUTING going to sleep
>>>> 2013-07-01 20:37:32.536989 [DEBUG] switch_core_state_machine.c:416
>>>> (sofia/sipinterface_2/14164790606) Running State Change CS_CONSUME_MEDIA
>>>> 2013-07-01 20:37:32.536989 [DEBUG] switch_core_state_machine.c:490
>>>> (sofia/sipinterface_2/14164790606) State CONSUME_MEDIA
>>>> 2013-07-01 20:37:32.536989 [DEBUG] switch_core_state_machine.c:490
>>>> (sofia/sipinterface_2/14164790606) State CONSUME_MEDIA going to sleep
>>>> 2013-07-01 20:37:32.536989 [DEBUG] switch_core_session.c:1006 Send
>>>> signal sofia/sipinterface_2/14164790606 [BREAK]
>>>> 2013-07-01 20:37:32.536989 [DEBUG] sofia.c:5764 Channel
>>>> sofia/sipinterface_2/14164790606 entering state [calling][0]
>>>> 2013-07-01 20:37:32.566973 [DEBUG] switch_core_session.c:1006 Send
>>>> signal sofia/sipinterface_2/14164790606 [BREAK]
>>>> 2013-07-01 20:37:32.566973 [DEBUG] switch_core_session.c:1006 Send
>>>> signal sofia/sipinterface_2/14164790606 [BREAK]
>>>> 2013-07-01 20:37:32.566973 [DEBUG] sofia.c:5764 Channel
>>>> sofia/sipinterface_2/14164790606 entering state [calling][0]
>>>> 2013-07-01 20:37:32.846981 [DEBUG] switch_core_session.c:1006 Send
>>>> signal sofia/sipinterface_1/barbara at alcan.mydomain.ca [BREAK]
>>>> 2013-07-01 20:37:32.857157 [DEBUG] switch_core_session.c:1006 Send
>>>> signal sofia/sipinterface_1/barbara at alcan.mydomain.ca [BREAK]
>>>> 2013-07-01 20:37:32.857157 [DEBUG] switch_core_session.c:1006 Send
>>>> signal sofia/sipinterface_1/barbara at alcan.mydomain.ca [BREAK]
>>>> 2013-07-01 20:37:32.857157 [DEBUG] sofia.c:5764 Channel
>>>> sofia/sipinterface_1/barbara at alcan.mydomain.ca entering state
>>>> [ready][200]
>>>> 2013-07-01 20:37:33.136982 [INFO] switch_rtp.c:4749 Auto Changing port
>>>> from 192.168.240.105:3000 to 99.247.64.220:34605
>>>> 2013-07-01 20:37:39.266976 [DEBUG] switch_core_session.c:1006 Send
>>>> signal sofia/sipinterface_2/14164790606 [BREAK]
>>>> 2013-07-01 20:37:39.266976 [DEBUG] switch_core_session.c:1006 Send
>>>> signal sofia/sipinterface_2/14164790606 [BREAK]
>>>> 2013-07-01 20:37:39.266976 [DEBUG] switch_core_session.c:1006 Send
>>>> signal sofia/sipinterface_2/14164790606 [BREAK]
>>>> 2013-07-01 20:37:39.266976 [DEBUG] switch_core_session.c:1006 Send
>>>> signal sofia/sipinterface_2/14164790606 [BREAK]
>>>> 2013-07-01 20:37:39.266976 [DEBUG] sofia.c:5764 Channel
>>>> sofia/sipinterface_2/14164790606 entering state [proceeding][180]
>>>> 2013-07-01 20:37:39.266976 [NOTICE] sofia.c:5854 Ring-Ready
>>>> sofia/sipinterface_2/14164790606!
>>>> 2013-07-01 20:37:39.266976 [DEBUG] switch_channel.c:3180
>>>> (sofia/sipinterface_2/14164790606) Callstate Change DOWN -> RINGING
>>>>
>>>>
>>>>
>>>> On Mon, Jul 1, 2013 at 8:18 PM, Avi Marcus <avi at avimarcus.net> wrote:
>>>>
>>>>> ... but it's not in the siptrace at all...
>>>>>
>>>>> Can we get more lines of the fs_cli log? From the setting of CID to
>>>>> the actual log?
>>>>>
>>>>> -Avi
>>>>>
>>>>> On Tue, Jul 2, 2013 at 3:10 AM, Moishe Grunstein <max at nysolutions.com>wrote:
>>>>>
>>>>>> What type of caller ID format is your ITSP expecting? Who is the ITSP?
>>>>>> ****
>>>>>>
>>>>>> http://wiki.freeswitch.org/wiki/Channel_Variables#Caller_ID_Related**
>>>>>> **
>>>>>>
>>>>>> ** **
>>>>>>
>>>>>> ** **
>>>>>>
>>>>>> ** **
>>>>>>
>>>>>> Thanks,****
>>>>>>
>>>>>> ** **
>>>>>>
>>>>>> Moishe Grunstein****
>>>>>>
>>>>>> Tornado Computer Systems, Inc.****
>>>>>>
>>>>>> 212.400.7650 888.IPPBX.US
>>>>>> *Service Request Email: support at nysolutions.com *****
>>>>>>
>>>>>> Polycom Certified VAR
>>>>>> Microsoft Small Business Specialist, Cisco SMB Select Certified****
>>>>>>
>>>>>> [image: cid:image001.jpg at 01C72F94.9EE45D60]<http://www.nysolutions.com/>
>>>>>> ****
>>>>>>
>>>>>> Computer Networking * Managed Services * IP Video Surveillance *
>>>>>> Network Assessments * Web Solutions * Voice over IP * Disaster Recovery *
>>>>>> Network Security * Site Surveys * CMS****
>>>>>>
>>>>>> ** **
>>>>>>
>>>>>> *From:* freeswitch-users-bounces at lists.freeswitch.org [mailto:
>>>>>> freeswitch-users-bounces at lists.freeswitch.org] *On Behalf Of *Lloyd
>>>>>> Aloysius
>>>>>> *Sent:* Monday, July 01, 2013 7:39 PM
>>>>>> *To:* FreeSWITCH Users Help
>>>>>> *Subject:* spam>spam>[Freeswitch-users] effective_caller_id_name
>>>>>> &effective_caller_id_number - not working****
>>>>>>
>>>>>> ** **
>>>>>>
>>>>>> Hi All,****
>>>>>>
>>>>>> ** **
>>>>>>
>>>>>> When sending calls out from gateway the caller id name and number not
>>>>>> working. Please find the freeswitch cli log and sip invite trace. Any help
>>>>>> is appreciated.****
>>>>>>
>>>>>> ** **
>>>>>>
>>>>>> *freeswitch cli*****
>>>>>>
>>>>>> ** **
>>>>>>
>>>>>> EXECUTE sofia/sipinterface_1/barbara at alcan.mydomain.caset(effective_caller_id_name=Alcan Cable)
>>>>>> ****
>>>>>>
>>>>>> 2013-07-01 19:23:21.676990 [DEBUG] mod_dptools.c:1373
>>>>>> sofia/sipinterface_1/barbara at alcan.mydomain.ca SET
>>>>>> [effective_caller_id_name]=[Alcan Cable]****
>>>>>>
>>>>>> EXECUTE sofia/sipinterface_1/barbara at alcan.mydomain.caset(effective_caller_id_number=
>>>>>> 9052066900)****
>>>>>>
>>>>>> 2013-07-01 19:23:21.676990 [DEBUG] mod_dptools.c:1373
>>>>>> sofia/sipinterface_1/barbara at alcan.mydomain.ca SET
>>>>>> [effective_caller_id_number]=[9052066900]****
>>>>>>
>>>>>> ** **
>>>>>>
>>>>>> *SIP Trace*****
>>>>>>
>>>>>> ** **
>>>>>>
>>>>>> ** **
>>>>>>
>>>>>> INVITE sip:4164750401 at alcan.mydomain.ca SIP/2.0****
>>>>>>
>>>>>> ** **
>>>>>>
>>>>>> Via: SIP/2.0/UDP 192.168.240.105:5060;branch=z9hG4bK17bfd0ad4****
>>>>>>
>>>>>> ** **
>>>>>>
>>>>>> Max-Forwards: 70****
>>>>>>
>>>>>> ** **
>>>>>>
>>>>>> Content-Length: 285****
>>>>>>
>>>>>> ** **
>>>>>>
>>>>>> To: 4164750401 <sip:4164750401 at alcan.mydomain.ca>****
>>>>>>
>>>>>> ** **
>>>>>>
>>>>>> From: Barbara Ladner <sip:barbara at alcan.mydomain.ca
>>>>>> >;tag=02f4abe9bef3a7f****
>>>>>>
>>>>>> ** **
>>>>>>
>>>>>> Call-ID: 43016333178f7b0882c3767aab886c1d at 192.168.240.105****
>>>>>>
>>>>>> ** **
>>>>>>
>>>>>> CSeq: 1894816474 INVITE****
>>>>>>
>>>>>> ** **
>>>>>>
>>>>>> Supported: timer****
>>>>>>
>>>>>> ** **
>>>>>>
>>>>>> Allow-Events: talk,hold,conference****
>>>>>>
>>>>>> ** **
>>>>>>
>>>>>> Allow:NOTIFY,REFER,OPTIONS,INVITE,ACK,CANCEL,BYE,INFO****
>>>>>>
>>>>>> ** **
>>>>>>
>>>>>> Content-Type: application/sdp****
>>>>>>
>>>>>> ** **
>>>>>>
>>>>>> Contact: Barbara Ladner <
>>>>>> sip:barbara at 192.168.240.105:5060;transport=udp>****
>>>>>>
>>>>>> ** **
>>>>>>
>>>>>> Supported: replaces****
>>>>>>
>>>>>> ** **
>>>>>>
>>>>>> User-Agent: Aastra 9133i/1.4.3.1001 Brcm Callctrl/1.5.1.0MxSF/v3.2.8.45
>>>>>> ****
>>>>>>
>>>>>> ** **
>>>>>>
>>>>>> ** **
>>>>>>
>>>>>> ** **
>>>>>>
>>>>>> v=0****
>>>>>>
>>>>>> ** **
>>>>>>
>>>>>> o=MxSIP 0 575681134 IN IP4 192.168.240.105****
>>>>>>
>>>>>> ** **
>>>>>>
>>>>>> s=SIP Call****
>>>>>>
>>>>>> ** **
>>>>>>
>>>>>> c=IN IP4 192.168.240.105****
>>>>>>
>>>>>> ** **
>>>>>>
>>>>>> t=0 0****
>>>>>>
>>>>>> ** **
>>>>>>
>>>>>> m=audio 3000 RTP/AVP 0 8 18 101****
>>>>>>
>>>>>> ** **
>>>>>>
>>>>>> a=rtpmap:0 PCMU/8000****
>>>>>>
>>>>>> ** **
>>>>>>
>>>>>> a=rtpmap:8 PCMA/8000****
>>>>>>
>>>>>> ** **
>>>>>>
>>>>>> a=rtpmap:18 G729/8000****
>>>>>>
>>>>>> ** **
>>>>>>
>>>>>> a=rtpmap:101 telephone-event/8000****
>>>>>>
>>>>>> ** **
>>>>>>
>>>>>> a=fmtp:101 0-15****
>>>>>>
>>>>>> ** **
>>>>>>
>>>>>> a=ptime:20****
>>>>>>
>>>>>> ** **
>>>>>>
>>>>>> a=silenceSupp:off - - - -****
>>>>>>
>>>>>> ** **
>>>>>>
>>>>>> ** **
>>>>>>
>>>>>> SIP/2.0 100 Trying****
>>>>>>
>>>>>> ** **
>>>>>>
>>>>>> Via: SIP/2.0/UDP 192.168.240.105:5060
>>>>>> ;branch=z9hG4bK17bfd0ad4;received=99.247.64.220;rport=16511****
>>>>>>
>>>>>> ** **
>>>>>>
>>>>>> From: Barbara Ladner <sip:barbara at alcan.mydomain.ca
>>>>>> >;tag=02f4abe9bef3a7f****
>>>>>>
>>>>>> ** **
>>>>>>
>>>>>> To: 4164750401 <sip:4164750401 at alcan.mydomain.ca>****
>>>>>>
>>>>>> ** **
>>>>>>
>>>>>> Call-ID: 43016333178f7b0882c3767aab886c1d at 192.168.240.105****
>>>>>>
>>>>>> ** **
>>>>>>
>>>>>> CSeq: 1894816474 INVITE****
>>>>>>
>>>>>> ** **
>>>>>>
>>>>>> User-Agent:
>>>>>> FreeSWITCH-mod_sofia/1.5.3b+git~20130628T160527Z~a52a604fbb****
>>>>>>
>>>>>> ** **
>>>>>>
>>>>>> Content-Length: 0****
>>>>>>
>>>>>> ** **
>>>>>>
>>>>>> ** **
>>>>>>
>>>>>> Thanks****
>>>>>>
>>>>>> Lloyd****
>>>>>>
>>>>>>
>>>>>> _________________________________________________________________________
>>>>>> Professional FreeSWITCH Consulting Services:
>>>>>> consulting at freeswitch.org
>>>>>> http://www.freeswitchsolutions.com
>>>>>>
>>>>>>
>>>>>>
>>>>>>
>>>>>> Official FreeSWITCH Sites
>>>>>> http://www.freeswitch.org
>>>>>> http://wiki.freeswitch.org
>>>>>> http://www.cluecon.com
>>>>>>
>>>>>> FreeSWITCH-users mailing list
>>>>>> FreeSWITCH-users at lists.freeswitch.org
>>>>>> http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
>>>>>> UNSUBSCRIBE:
>>>>>> http://lists.freeswitch.org/mailman/options/freeswitch-users
>>>>>> http://www.freeswitch.org
>>>>>>
>>>>>>
>>>>>
>>>>>
>>>>> _________________________________________________________________________
>>>>> Professional FreeSWITCH Consulting Services:
>>>>> consulting at freeswitch.org
>>>>> http://www.freeswitchsolutions.com
>>>>>
>>>>>
>>>>>
>>>>>
>>>>> Official FreeSWITCH Sites
>>>>> http://www.freeswitch.org
>>>>> http://wiki.freeswitch.org
>>>>> http://www.cluecon.com
>>>>>
>>>>> FreeSWITCH-users mailing list
>>>>> FreeSWITCH-users at lists.freeswitch.org
>>>>> http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
>>>>> UNSUBSCRIBE:
>>>>> http://lists.freeswitch.org/mailman/options/freeswitch-users
>>>>> http://www.freeswitch.org
>>>>>
>>>>>
>>>>
>>>>
>>>> _________________________________________________________________________
>>>> Professional FreeSWITCH Consulting Services:
>>>> consulting at freeswitch.org
>>>> http://www.freeswitchsolutions.com
>>>>
>>>>
>>>>
>>>>
>>>> Official FreeSWITCH Sites
>>>> http://www.freeswitch.org
>>>> http://wiki.freeswitch.org
>>>> http://www.cluecon.com
>>>>
>>>> FreeSWITCH-users mailing list
>>>> FreeSWITCH-users at lists.freeswitch.org
>>>> http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
>>>> UNSUBSCRIBE:
>>>> http://lists.freeswitch.org/mailman/options/freeswitch-users
>>>> http://www.freeswitch.org
>>>>
>>>>
>>>
>>>
>>> --
>>> Michael S Collins
>>> Twitter: @mercutioviz
>>> http://www.FreeSWITCH.org
>>> http://www.ClueCon.com
>>> http://www.OSTAG.org
>>>
>>>
>>> _________________________________________________________________________
>>> Professional FreeSWITCH Consulting Services:
>>> consulting at freeswitch.org
>>> http://www.freeswitchsolutions.com
>>>
>>>
>>>
>>>
>>> Official FreeSWITCH Sites
>>> http://www.freeswitch.org
>>> http://wiki.freeswitch.org
>>> http://www.cluecon.com
>>>
>>> FreeSWITCH-users mailing list
>>> FreeSWITCH-users at lists.freeswitch.org
>>> http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
>>> UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
>>> http://www.freeswitch.org
>>>
>>>
>>
>> _________________________________________________________________________
>> Professional FreeSWITCH Consulting Services:
>> consulting at freeswitch.org
>> http://www.freeswitchsolutions.com
>>
>>
>>
>>
>> Official FreeSWITCH Sites
>> http://www.freeswitch.org
>> http://wiki.freeswitch.org
>> http://www.cluecon.com
>>
>> FreeSWITCH-users mailing list
>> FreeSWITCH-users at lists.freeswitch.org
>> http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
>> UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
>> http://www.freeswitch.org
>>
>>
>
> _________________________________________________________________________
> Professional FreeSWITCH Consulting Services:
> consulting at freeswitch.org
> http://www.freeswitchsolutions.com
>
>
>
>
> Official FreeSWITCH Sites
> http://www.freeswitch.org
> http://wiki.freeswitch.org
> http://www.cluecon.com
>
> FreeSWITCH-users mailing list
> FreeSWITCH-users at lists.freeswitch.org
> http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
> UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
> http://www.freeswitch.org
>
>
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20130703/6c44d83b/attachment-0001.html
-------------- next part --------------
A non-text attachment was scrubbed...
Name: not available
Type: image/jpeg
Size: 2424 bytes
Desc: not available
Url : http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20130703/6c44d83b/attachment-0001.jpe
Join us at ClueCon 2011 Aug 9-11, 2011
More information about the FreeSWITCH-users
mailing list