Caller ID Not Showing Up From IAX Trunk

jonm229 · August 23, 2016, 7:40pm

We just installed a new FreePBX at a customer location. They use an IAX trunk back to our location, which uses a SIP trunk to our provider. Everything was working fine until a few days ago. Now, their caller ID shows up as “Unknown” when they make outbound calls. This had been working fine with no issues, as it is set in the Outbound Caller ID for the IAX trunk. I can see the Caller ID number in both their and my Asterisk log, but it is not being passed on to the SIP trunk. The Caller ID is also missing in the CDR Reports, where it was showing up before.

Here is the IAX trunk config:

OUR PBX
Trunk Name: toMCOP
PEER Details
host=xxx.xxx.xxx.xxx
username=mcop
secret=xxxxxxxxx
allow=g729&ulaw
type=peer
requirecalltoken=no
qualify=yes

USER Context: mcop
USER Details
context=from-internal
host=xxx.xxx.xxx.xxx
secret=xxxxxxxxx
requirecalltoken=no
allow=g729&ulaw
type=friend
qualify=yes

Customer PBX
Trunk Name: toFreePBX
PEER Details
host=xxx.xxx.xxx.xxx
username=mcop
secret=xxxxxx
allow=g729&ulaw
type=peer
qualify=yes

USER Context: mcop
USER Details
type=friend
secret=xxxxxx
host=xxx.xxx.xxx.xxx
allow=g729&ulaw
context=from-trunk
qualify=yes

jonm229 · August 23, 2016, 8:09pm

Here are the Asterisk logs from our own PBX transferring the call out to our SIP provider:

[2016-08-23 14:32:52] VERBOSE[2264][C-00024ad2] netsock2.c: == Using SIP RTP TOS bits 184
[2016-08-23 14:32:52] VERBOSE[2264][C-00024ad2] netsock2.c: == Using SIP RTP CoS mark 5
[2016-08-23 14:32:52] VERBOSE[26167][C-00024ad2] pbx.c: – Executing [[email protected]:1] Set(“SIP/Bandwidth.com-2-0002f112”, “GROUP()=OUT_3”) in new stack
[2016-08-23 14:32:52] VERBOSE[26167][C-00024ad2] pbx.c: – Executing [[email protected]:2] Goto(“SIP/Bandwidth.com-2-0002f112”, “from-trunk,CID,1”) in new stack
[2016-08-23 14:32:52] VERBOSE[26167][C-00024ad2] pbx.c: – Goto (from-trunk,CID1)
[2016-08-23 14:32:52] VERBOSE[26167][C-00024ad2] pbx.c: – Executing [CID@from-trunk:1] Set(“SIP/Bandwidth.com-2-0002f112”, “__DIRECTION=INBOUND”) in new stack
[2016-08-23 14:32:52] VERBOSE[26167][C-00024ad2] pbx.c: – Executing [CID@from-trunk:2] Gosub(“SIP/Bandwidth.com-2-0002f112”, “sub-record-check,s,1(in,CID,dontcare)”) in new stack
[2016-08-23 14:32:52] VERBOSE[26167][C-00024ad2] pbx.c: – Executing [s@sub-record-check:1] GotoIf(“SIP/Bandwidth.com-2-0002f112”, “0?initialized”) in new stack
[2016-08-23 14:32:52] VERBOSE[26167][C-00024ad2] pbx.c: – Executing [s@sub-record-check:2] Set(“SIP/Bandwidth.com-2-0002f112”, “__REC_STATUS=INITIALIZED”) in new stack
[2016-08-23 14:32:52] VERBOSE[26167][C-00024ad2] pbx.c: – Executing [s@sub-record-check:3] Set(“SIP/Bandwidth.com-2-0002f112”, “NOW=1471980772”) in new stack
[2016-08-23 14:32:52] VERBOSE[26167][C-00024ad2] pbx.c: – Executing [s@sub-record-check:4] Set(“SIP/Bandwidth.com-2-0002f112”, “__DAY=23”) in new stack
[2016-08-23 14:32:52] VERBOSE[26167][C-00024ad2] pbx.c: – Executing [s@sub-record-check:5] Set(“SIP/Bandwidth.com-2-0002f112”, “__MONTH=08”) in new stack
[2016-08-23 14:32:52] VERBOSE[26167][C-00024ad2] pbx.c: – Executing [s@sub-record-check:6] Set(“SIP/Bandwidth.com-2-0002f112”, “__YEAR=2016”) in new stack
[2016-08-23 14:32:52] VERBOSE[26167][C-00024ad2] pbx.c: – Executing [s@sub-record-check:7] Set(“SIP/Bandwidth.com-2-0002f112”, “__TIMESTR=20160823-143252”) in new stack
[2016-08-23 14:32:52] VERBOSE[26167][C-00024ad2] pbx.c: – Executing [s@sub-record-check:8] Set(“SIP/Bandwidth.com-2-0002f112”, “__FROMEXTEN=unknown”) in new stack
[2016-08-23 14:32:52] VERBOSE[26167][C-00024ad2] pbx.c: – Executing [s@sub-record-check:9] Set(“SIP/Bandwidth.com-2-0002f112”, “__MON_FMT=wav”) in new stack
[2016-08-23 14:32:52] VERBOSE[26167][C-00024ad2] pbx.c: – Executing [s@sub-record-check:10] NoOp(“SIP/Bandwidth.com-2-0002f112”, “Recordings initialized”) in new stack
[2016-08-23 14:32:52] VERBOSE[26167][C-00024ad2] pbx.c: – Executing [s@sub-record-check:11] ExecIf(“SIP/Bandwidth.com-2-0002f112”, “0?Set(ARG3=dontcare)”) in new stack
[2016-08-23 14:32:52] VERBOSE[26167][C-00024ad2] pbx.c: – Executing [s@sub-record-check:12] Set(“SIP/Bandwidth.com-2-0002f112”, “REC_POLICY_MODE_SAVE=”) in new stack
[2016-08-23 14:32:52] VERBOSE[26167][C-00024ad2] pbx.c: – Executing [s@sub-record-check:13] ExecIf(“SIP/Bandwidth.com-2-0002f112”, “0?Set(REC_STATUS=NO)”) in new stack
[2016-08-23 14:32:52] VERBOSE[26167][C-00024ad2] pbx.c: – Executing [s@sub-record-check:14] GotoIf(“SIP/Bandwidth.com-2-0002f112”, “2?checkaction”) in new stack
[2016-08-23 14:32:52] VERBOSE[26167][C-00024ad2] pbx.c: – Goto (sub-record-check,s,17)
[2016-08-23 14:32:52] VERBOSE[26167][C-00024ad2] pbx.c: – Executing [s@sub-record-check:17] GotoIf(“SIP/Bandwidth.com-2-0002f112”, “1?sub-record-check,in,1”) in new stack
[2016-08-23 14:32:52] VERBOSE[26167][C-00024ad2] pbx.c: – Goto (sub-record-check,in,1)
[2016-08-23 14:32:52] VERBOSE[26167][C-00024ad2] pbx.c: – Executing [in@sub-record-check:1] NoOp(“SIP/Bandwidth.com-2-0002f112”, “Inbound Recording Check to CID”) in new stack
[2016-08-23 14:32:52] VERBOSE[26167][C-00024ad2] pbx.c: – Executing [in@sub-record-check:2] Set(“SIP/Bandwidth.com-2-0002f112”, “FROMEXTEN=unknown”) in new stack
[2016-08-23 14:32:52] VERBOSE[26167][C-00024ad2] pbx.c: – Executing [in@sub-record-check:3] ExecIf(“SIP/Bandwidth.com-2-0002f112”, “10?Set(FROMEXTEN=3373640927)”) in new stack
[2016-08-23 14:32:52] VERBOSE[26167][C-00024ad2] pbx.c: – Executing [in@sub-record-check:4] Gosub(“SIP/Bandwidth.com-2-0002f112”, “recordcheck,1(dontcare,in,CID)”) in new stack
[2016-08-23 14:32:52] VERBOSE[26167][C-00024ad2] pbx.c: – Executing [recordcheck@sub-record-check:1] NoOp(“SIP/Bandwidth.com-2-0002f112”, “Starting recording check against dontcare”) in new stack
[2016-08-23 14:32:52] VERBOSE[26167][C-00024ad2] pbx.c: – Executing [recordcheck@sub-record-check:2] Goto(“SIP/Bandwidth.com-2-0002f112”, “dontcare”) in new stack
[2016-08-23 14:32:52] VERBOSE[26167][C-00024ad2] pbx.c: – Goto (sub-record-check,recordcheck,3)
[2016-08-23 14:32:52] VERBOSE[26167][C-00024ad2] pbx.c: – Executing [recordcheck@sub-record-check:3] Return(“SIP/Bandwidth.com-2-0002f112”, “”) in new stack
[2016-08-23 14:32:52] VERBOSE[26167][C-00024ad2] pbx.c: – Executing [in@sub-record-check:5] Return(“SIP/Bandwidth.com-2-0002f112”, “”) in new stack
[2016-08-23 14:32:52] VERBOSE[26167][C-00024ad2] pbx.c: – Executing [CID@from-trunk:3] Gosub(“SIP/Bandwidth.com-2-0002f112”, “app-blacklist-check,s,1()”) in new stack
[2016-08-23 14:32:52] VERBOSE[26167][C-00024ad2] pbx.c: – Executing [s@app-blacklist-check:1] GotoIf(“SIP/Bandwidth.com-2-0002f112”, “0?blacklisted”) in new stack
[2016-08-23 14:32:52] VERBOSE[26167][C-00024ad2] pbx.c: – Executing [s@app-blacklist-check:2] Set(“SIP/Bandwidth.com-2-0002f112”, “CALLED_BLACKLIST=1”) in new stack
[2016-08-23 14:32:52] VERBOSE[26167][C-00024ad2] pbx.c: – Executing [s@app-blacklist-check:3] Return(“SIP/Bandwidth.com-2-0002f112”, “”) in new stack
[2016-08-23 14:32:52] VERBOSE[26167][C-00024ad2] pbx.c: – Executing [CID@from-trunk:4] Set(“SIP/Bandwidth.com-2-0002f112”, “__FROM_DID=CID”) in new stack
[2016-08-23 14:32:52] VERBOSE[26167][C-00024ad2] pbx.c: – Executing [CID@from-trunk:5] Set(“SIP/Bandwidth.com-2-0002f112”, “CDR(did)=CID”) in new stack
[2016-08-23 14:32:52] VERBOSE[26167][C-00024ad2] pbx.c: – Executing [CID@from-trunk:6] ExecIf(“SIP/Bandwidth.com-2-0002f112”, “1 ?Set(CALLERID(name)=3373640927)”) in new stack
[2016-08-23 14:32:52] VERBOSE[26167][C-00024ad2] pbx.c: – Executing [CID@from-trunk:7] Set(“SIP/Bandwidth.com-2-0002f112”, “CHANNEL(musicclass)=default”) in new stack
[2016-08-23 14:32:52] VERBOSE[26167][C-00024ad2] pbx.c: – Executing [CID@from-trunk:8] Set(“SIP/Bandwidth.com-2-0002f112”, “__MOHCLASS=default”) in new stack
[2016-08-23 14:32:52] WARNING[26167][C-00024ad2] func_channel.c: Unknown or unavailable item requested: ‘reversecharge’
[2016-08-23 14:32:52] VERBOSE[26167][C-00024ad2] pbx.c: – Executing [CID@from-trunk:9] GotoIf(“SIP/Bandwidth.com-2-0002f112”, “0?macro-hangupcall”) in new stack
[2016-08-23 14:32:52] VERBOSE[26167][C-00024ad2] pbx.c: – Executing [CID@from-trunk:10] Set(“SIP/Bandwidth.com-2-0002f112”, “__CALLINGPRES_SV=allowed_not_screened”) in new stack
[2016-08-23 14:32:52] VERBOSE[26167][C-00024ad2] pbx.c: – Executing [CID@from-trunk:11] Set(“SIP/Bandwidth.com-2-0002f112”, “CALLERPRES()=allowed_not_screened”) in new stack
[2016-08-23 14:32:52] VERBOSE[26167][C-00024ad2] pbx.c: – Executing [CID@from-trunk:12] Goto(“SIP/Bandwidth.com-2-0002f112”, “ext-trunk,4,1”) in new stack
[2016-08-23 14:32:52] VERBOSE[26167][C-00024ad2] pbx.c: – Goto (ext-trunk,4,1)
[2016-08-23 14:32:52] VERBOSE[26167][C-00024ad2] pbx.c: – Executing [4@ext-trunk:1] Set(“SIP/Bandwidth.com-2-0002f112”, “TDIAL_STRING=IAX2/ToMCOP”) in new stack
[2016-08-23 14:32:52] VERBOSE[26167][C-00024ad2] pbx.c: – Executing [4@ext-trunk:2] Set(“SIP/Bandwidth.com-2-0002f112”, “DIAL_TRUNK=4”) in new stack
[2016-08-23 14:32:52] VERBOSE[26167][C-00024ad2] pbx.c: – Executing [4@ext-trunk:3] Goto(“SIP/Bandwidth.com-2-0002f112”, “ext-trunk,tdial,1”) in new stack
[2016-08-23 14:32:52] VERBOSE[26167][C-00024ad2] pbx.c: – Goto (ext-trunk,tdial,1)
[2016-08-23 14:32:52] VERBOSE[26167][C-00024ad2] pbx.c: – Executing [tdial@ext-trunk:1] Set(“SIP/Bandwidth.com-2-0002f112”, “OUTBOUND_GROUP=OUT_4”) in new stack
[2016-08-23 14:32:52] VERBOSE[26167][C-00024ad2] pbx.c: – Executing [tdial@ext-trunk:2] GotoIf(“SIP/Bandwidth.com-2-0002f112”, “1?nomax”) in new stack
[2016-08-23 14:32:52] VERBOSE[26167][C-00024ad2] pbx.c: – Goto (ext-trunk,tdial,4)
[2016-08-23 14:32:52] VERBOSE[26167][C-00024ad2] pbx.c: – Executing [tdial@ext-trunk:4] ExecIf(“SIP/Bandwidth.com-2-0002f112”, “1?Set(CALLERPRES()=allowed_not_screened)”) in new stack
[2016-08-23 14:32:52] VERBOSE[26167][C-00024ad2] pbx.c: – Executing [tdial@ext-trunk:5] Set(“SIP/Bandwidth.com-2-0002f112”, “DIAL_NUMBER=CID”) in new stack
[2016-08-23 14:32:52] VERBOSE[26167][C-00024ad2] pbx.c: – Executing [tdial@ext-trunk:6] GosubIf(“SIP/Bandwidth.com-2-0002f112”, “0?sub-flp-4,s,1()”) in new stack
[2016-08-23 14:32:52] VERBOSE[26167][C-00024ad2] pbx.c: – Executing [tdial@ext-trunk:7] Set(“SIP/Bandwidth.com-2-0002f112”, “OUTNUM=CID”) in new stack
[2016-08-23 14:32:52] VERBOSE[26167][C-00024ad2] pbx.c: – Executing [tdial@ext-trunk:8] Set(“SIP/Bandwidth.com-2-0002f112”, “DIAL_TRUNK_OPTIONS=Tt”) in new stack
[2016-08-23 14:32:52] VERBOSE[26167][C-00024ad2] pbx.c: – Executing [tdial@ext-trunk:9] Dial(“SIP/Bandwidth.com-2-0002f112”, “IAX2/ToMCOP/CID,Tt”) in new stack
[2016-08-23 14:32:52] VERBOSE[26167][C-00024ad2] app_dial.c: – Called IAX2/ToMCOP/CID
[2016-08-23 14:32:54] VERBOSE[2276][C-00024ad2] chan_iax2.c: – Call accepted by xxx.xxx.xxx.xxx (format alaw)
[2016-08-23 14:32:54] VERBOSE[2276][C-00024ad2] chan_iax2.c: – Format for call is (alaw)
[2016-08-23 14:32:54] VERBOSE[26167][C-00024ad2] app_dial.c: – IAX2/ToMCOP-7610 answered SIP/Bandwidth.com-2-0002f112
[2016-08-23 14:33:35] VERBOSE[26163][C-00024ad1] pbx.c: – Executing [h@macro-dialout-trunk:1] Macro(“IAX2/mcop-555”, “hangupcall,”) in new stack
[2016-08-23 14:33:35] VERBOSE[26163][C-00024ad1] pbx.c: – Executing [s@macro-hangupcall:1] ExecIf(“IAX2/mcop-555”, “0?Set(CDR(recordingfile)=.wav)”) in new stack
[2016-08-23 14:33:35] VERBOSE[26163][C-00024ad1] pbx.c: – Executing [s@macro-hangupcall:2] GotoIf(“IAX2/mcop-555”, “1?theend”) in new stack
[2016-08-23 14:33:35] VERBOSE[26163][C-00024ad1] pbx.c: – Goto (macro-hangupcall,s,4)
[2016-08-23 14:33:35] VERBOSE[26163][C-00024ad1] pbx.c: – Executing [s@macro-hangupcall:4] Hangup(“IAX2/mcop-555”, “”) in new stack
[2016-08-23 14:33:35] VERBOSE[26163][C-00024ad1] app_macro.c: == Spawn extension (macro-hangupcall, s, 4) exited non-zero on ‘IAX2/mcop-555’ in macro ‘hangupcall’
[2016-08-23 14:33:35] VERBOSE[26163][C-00024ad1] pbx.c: == Spawn extension (macro-dialout-trunk, h, 1) exited non-zero on ‘IAX2/mcop-555’
[2016-08-23 14:33:35] VERBOSE[26163][C-00024ad1] app_macro.c: == Spawn extension (macro-dialout-trunk, s, 22) exited non-zero on ‘IAX2/mcop-555’ in macro ‘dialout-trunk’
[2016-08-23 14:33:35] VERBOSE[26163][C-00024ad1] pbx.c: == Spawn extension (from-internal, DEST, 7) exited non-zero on ‘IAX2/mcop-555’
[2016-08-23 14:33:35] VERBOSE[26163][C-00024ad1] chan_iax2.c: – Hungup ‘IAX2/mcop-555’