[Freeswitch-users] effective_caller_id_name &effective_caller_id_number - not working

Wed Jul 3 09:51:58 MSD 2013

Dumb question: after you saved caller-id-in-from in the gateway configs,
did you killgw your utel gateway, then restart the gateway?

-Avi

On Wed, Jul 3, 2013 at 8:27 AM, Lloyd Aloysius <lloyd.aloysius at sunteltech.ca
> wrote:

> 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
>>
>>
>
> _________________________________________________________________________
> 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/df4cc8bb/attachment-0001.html 