[Freeswitch-users] effective_caller_id_name &effective_caller_id_number - not working

Tue Jul 2 11:49:33 MSD 2013

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.ca XML
>>> 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.ca XML
>>> 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
>
>
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20130702/693eb448/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/20130702/693eb448/attachment-0001.jpe 