Lost CNAM on inbound calls

I hope someone can figure this out, I am at a loss and my backups do not fix the issue.

All incoming calls via Sipstation and I bought another did via FlowRoute to see what was going on. Neither are producing CNAM on the inbound calls, they are both producing numbers +1 and the 10 digit, but instead of a name, it is using the onbound phone number.

Nothing on my system has changed, no one has been in apart from SSH from Sipstation resolving an issue with the Sipstation module. Two other major things is new framework and the new Sipstation module.

Tony Lewis has been working with me, but they are unable to see any issues their end, and I can confirm this is an issue on my server some how.

Can anyone point out something I should be looking into? bearing in mind, I am still not proficient with Linux, but I can find my way around.

Here is a trace on an inbound call using sipstation.

asteriskCLI> core set verbose 5
Verbosity is at least 5
asterisk
CLI>
asteriskCLI> logger rotate
== Parsing ‘/etc/asterisk/logger.conf’: == Found
== Parsing ‘/etc/asterisk/logger_general_additional.conf’: == Found
== Parsing ‘/etc/asterisk/logger_general_custom.conf’: == Found
== Parsing ‘/etc/asterisk/logger_logfiles_additional.conf’: == Found
== Parsing ‘/etc/asterisk/logger_logfiles_custom.conf’: == Found
Asterisk Queue Logger restarted
== Using UDPTL TOS bits 184
== Using UDPTL CoS mark 5
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
– Executing [[email protected]:1] Set(“SIP/fpbx-1-2e4ea3ce-0000001b”, “__FROM_DID=3045551234”) in new stack
– Executing [[email protected]:2] Gosub(“SIP/fpbx-1-2e4ea3ce-0000001b”, “app-blacklist-check,s,1()”) in new stack
– Executing [[email protected]:1] GotoIf(“SIP/fpbx-1-2e4ea3ce-0000001b”, “0?blacklisted”) in new stack
– Executing [[email protected]:2] Set(“SIP/fpbx-1-2e4ea3ce-0000001b”, “CALLED_BLACKLIST=1”) in new stack
– Executing [[email protected]:3] Return(“SIP/fpbx-1-2e4ea3ce-0000001b”, “”) in new stack
– Executing [[email protected]:3] Set(“SIP/fpbx-1-2e4ea3ce-0000001b”, “CDR(did)=3045551234”) in new stack
– Executing [[email protected]:4] ExecIf(“SIP/fpbx-1-2e4ea3ce-0000001b”, “0 ?Set(CALLERID(name)=+13047771234)”) in new stack
– Executing [[email protected]:5] Macro(“SIP/fpbx-1-2e4ea3ce-0000001b”, “privacy-mgr,3,6”) in new stack
– Executing [[email protected]:1] Set(“SIP/fpbx-1-2e4ea3ce-0000001b”, “KEEPCID=+13047771234”) in new stack
– Executing [[email protected]:2] GotoIf(“SIP/fpbx-1-2e4ea3ce-0000001b”, “1?CIDTEST2:CIDTEST1”) in new stack
– Goto (macro-privacy-mgr,s,5)
– Executing [[email protected]:5] Set(“SIP/fpbx-1-2e4ea3ce-0000001b”, “TESTCID=13046920337.000000”) in new stack
– Executing [[email protected]:6] GotoIf(“SIP/fpbx-1-2e4ea3ce-0000001b”, “0?CLEARCID:PRIVMGR”) in new stack
– Goto (macro-privacy-mgr,s,8)
– Executing [[email protected]:8] PrivacyManager(“SIP/fpbx-1-2e4ea3ce-0000001b”, “3,6”) in new stack
– CallerID number present: Skipping
– Executing [[email protected]:9] GotoIf(“SIP/fpbx-1-2e4ea3ce-0000001b”, “0?fail”) in new stack
– Executing [[email protected]:10] GosubIf(“SIP/fpbx-1-2e4ea3ce-0000001b”, “1?app-blacklist-check,s,1”) in new stack
– Executing [[email protected]:1] GotoIf(“SIP/fpbx-1-2e4ea3ce-0000001b”, “0?blacklisted”) in new stack
– Executing [[email protected]:2] Set(“SIP/fpbx-1-2e4ea3ce-0000001b”, “CALLED_BLACKLIST=1”) in new stack
– Executing [[email protected]:3] Return(“SIP/fpbx-1-2e4ea3ce-0000001b”, “”) in new stack
– Executing [[email protected]:11] SetCallerPres(“SIP/fpbx-1-2e4ea3ce-0000001b”, “allowed_passed_screen”) in new stack
– Executing [[email protected]:6] Goto(“SIP/fpbx-1-2e4ea3ce-0000001b”, “timeconditions,1,1”) in new stack
– Goto (timeconditions,1,1)
– Executing [[email protected]:1] GotoIfTime(“SIP/fpbx-1-2e4ea3ce-0000001b”, “21:00-06:00,mon-sun,1-31,jan-dec?truestate”) in new stack
– Executing [[email protected]:2] GotoIf(“SIP/fpbx-1-2e4ea3ce-0000001b”, “0?truegoto”) in new stack
– Executing [[email protected]:3] ExecIf(“SIP/fpbx-1-2e4ea3ce-0000001b”, “0?Set(DB(TC/1)=)”) in new stack
– Executing [[email protected]:4] GotoIf(“SIP/fpbx-1-2e4ea3ce-0000001b”, “1?ext-group,600,1”) in new stack
– Goto (ext-group,600,1)
– Executing [[email protected]:1] Macro(“SIP/fpbx-1-2e4ea3ce-0000001b”, “user-callerid,”) in new stack
– Executing [[email protected]:1] Set(“SIP/fpbx-1-2e4ea3ce-0000001b”, “AMPUSER=+13047771234”) in new stack
– Executing [[email protected]:2] GotoIf(“SIP/fpbx-1-2e4ea3ce-0000001b”, “0?report”) in new stack
– Executing [[email protected]:3] ExecIf(“SIP/fpbx-1-2e4ea3ce-0000001b”, “1?Set(REALCALLERIDNUM=+13047771234)”) in new stack
– Executing [[email protected]:4] Set(“SIP/fpbx-1-2e4ea3ce-0000001b”, “AMPUSER=”) in new stack
– Executing [[email protected]:5] Set(“SIP/fpbx-1-2e4ea3ce-0000001b”, “AMPUSERCIDNAME=”) in new stack
– Executing [[email protected]:6] GotoIf(“SIP/fpbx-1-2e4ea3ce-0000001b”, “1?report”) in new stack
– Goto (macro-user-callerid,s,12)
– Executing [[email protected]:12] GotoIf(“SIP/fpbx-1-2e4ea3ce-0000001b”, “0?continue”) in new stack
– Executing [[email protected]:13] Set(“SIP/fpbx-1-2e4ea3ce-0000001b”, “__TTL=64”) in new stack
– Executing [[email protected]:14] GotoIf(“SIP/fpbx-1-2e4ea3ce-0000001b”, “1?continue”) in new stack
– Goto (macro-user-callerid,s,25)
– Executing [[email protected]:25] Set(“SIP/fpbx-1-2e4ea3ce-0000001b”, “CALLERID(number)=+13047771234”) in new stack
– Executing [[email protected]:26] Set(“SIP/fpbx-1-2e4ea3ce-0000001b”, “CALLERID(name)=+13047771234”) in new stack
– Executing [[email protected]:27] Set(“SIP/fpbx-1-2e4ea3ce-0000001b”, “CHANNEL(language)=en”) in new stack
– Executing [[email protected]:2] Macro(“SIP/fpbx-1-2e4ea3ce-0000001b”, “blkvm-setifempty,”) in new stack
– Executing [[email protected]:1] GotoIf(“SIP/fpbx-1-2e4ea3ce-0000001b”, “1?init”) in new stack
– Goto (macro-blkvm-setifempty,s,4)
– Executing [[email protected]:4] Set(“SIP/fpbx-1-2e4ea3ce-0000001b”, “__BLKVM_CHANNEL=SIP/fpbx-1-2e4ea3ce-0000001b”) in new stack
– Executing [[email protected]:5] Set(“SIP/fpbx-1-2e4ea3ce-0000001b”, “SHARED(BLKVM,SIP/fpbx-1-2e4ea3ce-0000001b)=TRUE”) in new stack
– Executing [[email protected]:6] Set(“SIP/fpbx-1-2e4ea3ce-0000001b”, “GOSUB_RETVAL=TRUE”) in new stack
– Executing [[email protected]:7] MacroExit(“SIP/fpbx-1-2e4ea3ce-0000001b”, “”) in new stack
– Executing [[email protected]:3] GotoIf(“SIP/fpbx-1-2e4ea3ce-0000001b”, “1?skipov”) in new stack
– Goto (ext-group,600,6)
– Executing [[email protected]:6] Set(“SIP/fpbx-1-2e4ea3ce-0000001b”, “RRNODEST=”) in new stack
– Executing [[email protected]:7] Set(“SIP/fpbx-1-2e4ea3ce-0000001b”, “__NODEST=600”) in new stack
– Executing [[email protected]:8] GosubIf(“SIP/fpbx-1-2e4ea3ce-0000001b”, “0?sub-rgsetcid,s,1()”) in new stack
– Executing [[email protected]:9] Set(“SIP/fpbx-1-2e4ea3ce-0000001b”, “__CWIGNORE=TRUE”) in new stack
– Executing [[email protected]:10] Set(“SIP/fpbx-1-2e4ea3ce-0000001b”, “__PICKUPMARK=600”) in new stack
– Executing [[email protected]:11] Gosub(“SIP/fpbx-1-2e4ea3ce-0000001b”, “sub-record-check,s,1(rg,600,dontcare)”) in new stack
– Executing [[email protected]:1] GotoIf(“SIP/fpbx-1-2e4ea3ce-0000001b”, “1?check”) in new stack
– Goto (sub-record-check,s,6)
– Executing [[email protected]:6] Set(“SIP/fpbx-1-2e4ea3ce-0000001b”, “__MON_FMT=wav”) in new stack
– Executing [[email protected]:7] GotoIf(“SIP/fpbx-1-2e4ea3ce-0000001b”, “1?next”) in new stack
– Goto (sub-record-check,s,10)
– Executing [[email protected]:10] ExecIf(“SIP/fpbx-1-2e4ea3ce-0000001b”, “0?Return()”) in new stack
– Executing [[email protected]:11] GotoIf(“SIP/fpbx-1-2e4ea3ce-0000001b”, “0?rg,1”) in new stack
– Executing [[email protected]:12] Set(“SIP/fpbx-1-2e4ea3ce-0000001b”, “__REC_STATUS=INITIALIZED”) in new stack
– Executing [[email protected]:13] ExecIf(“SIP/fpbx-1-2e4ea3ce-0000001b”, “1?Set(__REC_POLICY_MODE=dontcare)”) in new stack
– Executing [[email protected]:14] Set(“SIP/fpbx-1-2e4ea3ce-0000001b”, “NOW=1363301410”) in new stack
– Executing [[email protected]:15] Set(“SIP/fpbx-1-2e4ea3ce-0000001b”, “__DAY=14”) in new stack
– Executing [[email protected]:16] Set(“SIP/fpbx-1-2e4ea3ce-0000001b”, “__MONTH=03”) in new stack
– Executing [[email protected]:17] Set(“SIP/fpbx-1-2e4ea3ce-0000001b”, “__YEAR=2013”) in new stack
– Executing [[email protected]:18] Set(“SIP/fpbx-1-2e4ea3ce-0000001b”, “__TIMESTR=20130314-185010”) in new stack
– Executing [[email protected]:19] Set(“SIP/fpbx-1-2e4ea3ce-0000001b”, “__FROMEXTEN=+13047771234”) in new stack
– Executing [[email protected]:20] Set(“SIP/fpbx-1-2e4ea3ce-0000001b”, “__CALLFILENAME=rg-600-+13047771234-20130314-185010-1363301410.27”) in new stack
– Executing [[email protected]:21] Goto(“SIP/fpbx-1-2e4ea3ce-0000001b”, “rg,1”) in new stack
– Goto (sub-record-check,rg,1)
– Executing [[email protected]:1] GosubIf(“SIP/fpbx-1-2e4ea3ce-0000001b”, “0?record,1(rg,dontcare,+13047771234)”) in new stack
– Executing [[email protected]:2] Return(“SIP/fpbx-1-2e4ea3ce-0000001b”, “”) in new stack
– Executing [[email protected]:12] Set(“SIP/fpbx-1-2e4ea3ce-0000001b”, “RingGroupMethod=ringall”) in new stack
– Executing [[email protected]:13] Macro(“SIP/fpbx-1-2e4ea3ce-0000001b”, “dial,20,tr,101-103-105-106-112”) in new stack
– Executing [[email protected]:1] GotoIf(“SIP/fpbx-1-2e4ea3ce-0000001b”, “1?dial”) in new stack
– Goto (macro-dial,s,3)
– Executing [[email protected]:3] AGI(“SIP/fpbx-1-2e4ea3ce-0000001b”, “dialparties.agi”) in new stack
– Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
dialparties.agi: Starting New Dialparties.agi
dialparties.agi: Caller ID name is ‘+13047771234’ number is ‘+13047771234’
> dialparties.agi: USE_CONFIRMATION: ‘FALSE’
> dialparties.agi: RINGGROUP_INDEX: ''
dialparties.agi: Methodology of ring is ‘ringall’
– dialparties.agi: Added extension 101 to extension map
– dialparties.agi: Added extension 103 to extension map
– dialparties.agi: Added extension 105 to extension map
– dialparties.agi: Added extension 106 to extension map
– dialparties.agi: Added extension 112 to extension map
– dialparties.agi: Extension 101 cf is disabled
– dialparties.agi: Extension 103 cf is disabled
– dialparties.agi: Extension 105 cf is disabled
– dialparties.agi: Extension 106 cf is disabled
– dialparties.agi: Extension 112 cf is disabled
– dialparties.agi: Extension 101 do not disturb is disabled
– dialparties.agi: Extension 103 do not disturb is disabled
– dialparties.agi: Extension 105 do not disturb is disabled
– dialparties.agi: Extension 106 do not disturb is disabled
– dialparties.agi: Extension 112 do not disturb is disabled
> dialparties.agi: extnum 101 has: cw: 0; hascfb: 0 [] hascfu: 0 []
dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE)
dialparties.agi: Extension 101 has ExtensionState: 0
– dialparties.agi: Checking CW and CFB status for extension 101
– dialparties.agi: dbset CALLTRACE/101 to +13047771234
> dialparties.agi: extnum 103 has: cw: 0; hascfb: 0 [] hascfu: 0 []
dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE)
dialparties.agi: Extension 103 has ExtensionState: 0
– dialparties.agi: Checking CW and CFB status for extension 103
– dialparties.agi: dbset CALLTRACE/103 to +13047771234
> dialparties.agi: extnum 105 has: cw: 0; hascfb: 0 [] hascfu: 0 []
dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE)
dialparties.agi: Extension 105 has ExtensionState: 0
– dialparties.agi: Checking CW and CFB status for extension 105
– dialparties.agi: dbset CALLTRACE/105 to +13047771234
> dialparties.agi: extnum 106 has: cw: 0; hascfb: 0 [] hascfu: 0 []
dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE)
dialparties.agi: Extension 106 has ExtensionState: 0
– dialparties.agi: Checking CW and CFB status for extension 106
– dialparties.agi: dbset CALLTRACE/106 to +13047771234
> dialparties.agi: extnum 112 has: cw: 0; hascfb: 0 [] hascfu: 0 []
dialparties.agi: EXTENSION_STATE: 4 (UNAVAILABLE)
dialparties.agi: Extension 112 has ExtensionState: 4
– dialparties.agi: Checking CW and CFB status for extension 112
– dialparties.agi: dbset CALLTRACE/112 to +13047771234
– dialparties.agi: Filtered ARG3: 101-103-105-106-112
> dialparties.agi: NODEST: 600 adding M(auto-blkvm) to dialopts: trM(auto-blkvm)
> dialparties.agi: NODEST: 600 blkvm enabled macro already in dialopts: trM(auto-blkvm)
– <SIP/fpbx-1-2e4ea3ce-0000001b>AGI Script dialparties.agi completed, returning 0
– Executing [[email protected]:7] Dial(“SIP/fpbx-1-2e4ea3ce-0000001b”, “SIP/101&SIP/103&SIP/105&SIP/106&SIP/112,20,trM(auto-blkvm)”) in new stack
== Using UDPTL TOS bits 184
== Using UDPTL CoS mark 5
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
– Called SIP/101
== Using UDPTL TOS bits 184
== Using UDPTL CoS mark 5
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
– Called SIP/103
== Using UDPTL TOS bits 184
== Using UDPTL CoS mark 5
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
– Called SIP/105
== Using UDPTL TOS bits 184
== Using UDPTL CoS mark 5
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
– Called SIP/106
== Using UDPTL TOS bits 184
== Using UDPTL CoS mark 5
[2013-03-14 18:50:10] WARNING[22736]: app_dial.c:2218 dial_exec_full: Unable to create channel of type ‘SIP’ (cause 20 - Unknown)
– SIP/101-0000001c connected line has changed. Saving it until answer for SIP/fpbx-1-2e4ea3ce-0000001b
– SIP/103-0000001d connected line has changed. Saving it until answer for SIP/fpbx-1-2e4ea3ce-0000001b
– SIP/105-0000001e connected line has changed. Saving it until answer for SIP/fpbx-1-2e4ea3ce-0000001b
– SIP/106-0000001f connected line has changed. Saving it until answer for SIP/fpbx-1-2e4ea3ce-0000001b
– SIP/105-0000001e is ringing
– SIP/106-0000001f is ringing
– SIP/101-0000001c is ringing
– SIP/103-0000001d is ringing
== Spawn extension (macro-dial, s, 7) exited non-zero on ‘SIP/fpbx-1-2e4ea3ce-0000001b’ in macro ‘dial’
== Spawn extension (ext-group, 600, 13) exited non-zero on ‘SIP/fpbx-1-2e4ea3ce-0000001b’
– Executing [[email protected]:1] Macro(“SIP/fpbx-1-2e4ea3ce-0000001b”, “hangupcall,”) in new stack
– Executing [[email protected]:1] GotoIf(“SIP/fpbx-1-2e4ea3ce-0000001b”, “1?theend”) in new stack
– Goto (macro-hangupcall,s,3)
– Executing [[email protected]:3] ExecIf(“SIP/fpbx-1-2e4ea3ce-0000001b”, “0?Set(CDR(recordingfile)=)”) in new stack
– Executing [[email protected]:4] Hangup(“SIP/fpbx-1-2e4ea3ce-0000001b”, “”) in new stack
== Spawn extension (macro-hangupcall, s, 4) exited non-zero on ‘SIP/fpbx-1-2e4ea3ce-0000001b’ in macro ‘hangupcall’
== Spawn extension (ext-group, h, 1) exited non-zero on 'SIP/fpbx-1-2e4ea3ce-0000001b’
asterisk
CLI>

Anyone? I really don’t want to reload from scratch, but if I have to… I guess that is the only fix.

I don’t think reloading is going to do anything.

Do these providers even sand CNAME information? I thought you had to define a Caller ID lookup source, I use BulkCNAM.

It always worked before, I use Sipstation and I was having some issues with the module, but they got it fixed. Weird thing is, around that time it stopped working but I also had a framework update too. No matter what I try, it will not work, I am at such a loss. I would not mind so much, but this is my primary box and number… I even bought another trunk and DID using another service, same situation with them.

My guess is you used to have CID superfecta or something installed.

These providers don’t give you name only number. The DIPS are just too damn expensive.

Thanks for the reply, but it did this out of the box for me. Never did I set up anything to do the look up, I naturally presumed it came from the SIP provider. Now I am totally confused as to why this has stopped to function.

This was an original distro 1.8.1.4 net. Obviously it has had some upgrades to get it to the version it is at now. All along up until a few days ago, I always had a name to the CID.

I do appreciate your help in this matter.

Just for informational purposes.

From Sipstaions website.

(Q) Do you provide CNAM (Caller ID Name) service?
(A) Our inbound DIDs are delivered with CNAM name service.

Interestingly enough, my internal Caller ID (Name) is still working.

Sipstation are telling me, everything is fine on there end and that they are receiving the CNAM on there system.

I have a feeling that it has something to do with the framework v2.10.1.9 that was updated a few days ago.

Time to frag the install and reload it, at this point I have no options left, I can’t remove the framework as it is not an option, so I am unable to see if this is actually affecting my system. I can’t trouble shoot anymore due to the above, in fact, even if I do remove the install and reload, I could end up with the same results due to the versions now released.

Something has broke, I even tried a free CNAM service and I am getting the same results. So maybe there is an issue with the framework and CNAM… Can someone maybe look into this for me? or do I risk loosing my setup just to find the same results?

That’s interesting on the Sipstation CNAM. It is a premium product so I should not be surprised.

Reinstalling is just ridiculous. If you really believe you have a corrupt FreePBX framework (doubtful) you could just run the install_amp script and tell it to refresh your files.

You can force install all modules with module_admin. That’s what these tools are for. The GUI is just the surface.

What trouble shooting have you done? Is the CNAM in the SIP invite if you do a debug? What about a call trace? Tried disabling RPID options?

I have done the CLI script to show the information at the top of this post. Checked the module and re-installed. I am such a noob after so many years of using this system, it is SOOOOOO reliable that I do not need to touch it. The way I look at things, if it is not broke, don’t fix it you know!

So I am going to try and attempt to find information on what you said, I hate to ask you to baby sit me and walk me through it all, but is there anyway you can baby sit me and walk me through? lol.

If you don’t mind, just throw some commands at me, just in case I can locate anything on this…

I have to learn more about linux, and I have been saying that for a while, a long while.

Again, much appreciated for your help and for what you do around the forum…

I ran the install_amp script from /usr/src/freepbx-2.10.0rc1 which is the latest I had. Amportal restart just to make sure… same results… Rebooted the server to make double sure… same results. I do now have 63 modules to update, but I avoided doing that to see if it was any upgrades in the past, of which it does not seem to be.

I followed one of your older topics, and did the following…

Call trace
log in to shell…

‘asterisk -r’ this enters asterisk CLI
’core set verbose 0’ this turns dial plan logging off
’core set debug 0’ this turns system debugging off
’sip set debug ip x.x.x.x’ where x is source or destination IP you want to capture.

Now place your calls

‘sip set debug off’
‘exit’

Your capture is in var/log/asterisk/full

Here is the result.
[2013-03-16 09:23:21] WARNING[6411] app_dial.c: Unable to create channel of type ‘SIP’ (cause 20 - Unknown)

Not really understanding what this means, I am off to do a quick search.

Turns out that is not very important.

You should see quite a bit more than that. You need to check that log right after you make the inbound call.

Ok, Now I can see the issue, and it was something to do with what Philippe Lindheimer had changed on my system, the jquery was depreciated and he made the update.
Here is what he told me in the ticket…
ok it appears sipstation is using a deprecated jQuery function which I fixed on your system

If you look at line 16 below, it shows this…
[2013-03-16 14:58:09] WARNING[11701] func_callerid.c: CALLERPRES is deprecated. Use CALLERID(name-pres) or CALLERID(num-pres) instead.

I wonder if this has something to do with why my caller ID is not longer working? If so, how do I go about finding and fixing that?

Numbers changed on purpose…

[2013-03-16 14:57:45] VERBOSE[11654] logger.c: Asterisk Queue Logger restarted [2013-03-16 14:58:09] VERBOSE[8228] netsock.c: == Using UDPTL TOS bits 184 [2013-03-16 14:58:09] VERBOSE[8228] netsock.c: == Using UDPTL CoS mark 5 [2013-03-16 14:58:09] VERBOSE[8228] netsock2.c: == Using SIP RTP TOS bits 184 [2013-03-16 14:58:09] VERBOSE[8228] netsock2.c: == Using SIP RTP CoS mark 5 [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:1] Set("SIP/fpbx-1-2e4ea3ce-00000009", "CALLERID(number)=3045551234") in new stack [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:2] Goto("SIP/fpbx-1-2e4ea3ce-00000009", "from-pstn,3046661234,1") in new stack [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Goto (from-pstn,3046661234,1) [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:1] Set("SIP/fpbx-1-2e4ea3ce-00000009", "__FROM_DID=3046661234") in new stack [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:2] Gosub("SIP/fpbx-1-2e4ea3ce-00000009", "app-blacklist-check,s,1()") in new stack [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:1] GotoIf("SIP/fpbx-1-2e4ea3ce-00000009", "0?blacklisted") in new stack [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:2] Set("SIP/fpbx-1-2e4ea3ce-00000009", "CALLED_BLACKLIST=1") in new stack [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:3] Return("SIP/fpbx-1-2e4ea3ce-00000009", "") in new stack [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:3] Set("SIP/fpbx-1-2e4ea3ce-00000009", "CDR(did)=3046661234") in new stack [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:4] ExecIf("SIP/fpbx-1-2e4ea3ce-00000009", "0 ?Set(CALLERID(name)=3045551234)") in new stack [2013-03-16 14:58:09] WARNING[11701] func_callerid.c: CALLERPRES is deprecated. Use CALLERID(name-pres) or CALLERID(num-pres) instead. [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:5] Set("SIP/fpbx-1-2e4ea3ce-00000009", "__CALLINGPRES_SV=allowed_not_screened") in new stack [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:6] Set("SIP/fpbx-1-2e4ea3ce-00000009", "CALLERPRES()=allowed_not_screened") in new stack [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:7] Goto("SIP/fpbx-1-2e4ea3ce-00000009", "ext-group,600,1") in new stack [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Goto (ext-group,600,1) [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:1] Macro("SIP/fpbx-1-2e4ea3ce-00000009", "user-callerid,") in new stack [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:1] Set("SIP/fpbx-1-2e4ea3ce-00000009", "AMPUSER=3045551234") in new stack [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:2] GotoIf("SIP/fpbx-1-2e4ea3ce-00000009", "0?report") in new stack [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:3] ExecIf("SIP/fpbx-1-2e4ea3ce-00000009", "1?Set(REALCALLERIDNUM=3045551234)") in new stack [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:4] Set("SIP/fpbx-1-2e4ea3ce-00000009", "AMPUSER=") in new stack [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:5] Set("SIP/fpbx-1-2e4ea3ce-00000009", "AMPUSERCIDNAME=") in new stack [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:6] GotoIf("SIP/fpbx-1-2e4ea3ce-00000009", "1?report") in new stack [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Goto (macro-user-callerid,s,13) [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:13] GotoIf("SIP/fpbx-1-2e4ea3ce-00000009", "0?continue") in new stack [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:14] Set("SIP/fpbx-1-2e4ea3ce-00000009", "__TTL=64") in new stack [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:15] GotoIf("SIP/fpbx-1-2e4ea3ce-00000009", "1?continue") in new stack [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Goto (macro-user-callerid,s,26) [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:26] Set("SIP/fpbx-1-2e4ea3ce-00000009", "CALLERID(number)=3045551234") in new stack [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:27] Set("SIP/fpbx-1-2e4ea3ce-00000009", "CALLERID(name)=+13045551234") in new stack [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:28] Set("SIP/fpbx-1-2e4ea3ce-00000009", "CHANNEL(language)=en") in new stack [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:2] Macro("SIP/fpbx-1-2e4ea3ce-00000009", "blkvm-setifempty,") in new stack [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:1] GotoIf("SIP/fpbx-1-2e4ea3ce-00000009", "1?init") in new stack [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Goto (macro-blkvm-setifempty,s,4) [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:4] Set("SIP/fpbx-1-2e4ea3ce-00000009", "__BLKVM_CHANNEL=SIP/fpbx-1-2e4ea3ce-00000009") in new stack [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:5] Set("SIP/fpbx-1-2e4ea3ce-00000009", "SHARED(BLKVM,SIP/fpbx-1-2e4ea3ce-00000009)=TRUE") in new stack [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:6] Set("SIP/fpbx-1-2e4ea3ce-00000009", "GOSUB_RETVAL=TRUE") in new stack [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:7] MacroExit("SIP/fpbx-1-2e4ea3ce-00000009", "") in new stack [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:3] GotoIf("SIP/fpbx-1-2e4ea3ce-00000009", "1?skipov") in new stack [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Goto (ext-group,600,6) [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:6] Set("SIP/fpbx-1-2e4ea3ce-00000009", "RRNODEST=") in new stack [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:7] Set("SIP/fpbx-1-2e4ea3ce-00000009", "__NODEST=600") in new stack [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:8] GosubIf("SIP/fpbx-1-2e4ea3ce-00000009", "0?sub-rgsetcid,s,1()") in new stack [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:9] Set("SIP/fpbx-1-2e4ea3ce-00000009", "__CWIGNORE=TRUE") in new stack [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:10] Set("SIP/fpbx-1-2e4ea3ce-00000009", "__PICKUPMARK=600") in new stack [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:11] Gosub("SIP/fpbx-1-2e4ea3ce-00000009", "sub-record-check,s,1(rg,600,dontcare)") in new stack [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:1] GotoIf("SIP/fpbx-1-2e4ea3ce-00000009", "1?check") in new stack [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Goto (sub-record-check,s,6) [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:6] Set("SIP/fpbx-1-2e4ea3ce-00000009", "__MON_FMT=wav") in new stack [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:7] GotoIf("SIP/fpbx-1-2e4ea3ce-00000009", "1?next") in new stack [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Goto (sub-record-check,s,10) [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:10] ExecIf("SIP/fpbx-1-2e4ea3ce-00000009", "0?Return()") in new stack [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:11] GotoIf("SIP/fpbx-1-2e4ea3ce-00000009", "0?rg,1") in new stack [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:12] Set("SIP/fpbx-1-2e4ea3ce-00000009", "__REC_STATUS=INITIALIZED") in new stack [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:13] ExecIf("SIP/fpbx-1-2e4ea3ce-00000009", "1?Set(__REC_POLICY_MODE=dontcare)") in new stack [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:14] Set("SIP/fpbx-1-2e4ea3ce-00000009", "NOW=1363460289") in new stack [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:15] Set("SIP/fpbx-1-2e4ea3ce-00000009", "__DAY=16") in new stack [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:16] Set("SIP/fpbx-1-2e4ea3ce-00000009", "__MONTH=03") in new stack [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:17] Set("SIP/fpbx-1-2e4ea3ce-00000009", "__YEAR=2013") in new stack [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:18] Set("SIP/fpbx-1-2e4ea3ce-00000009", "__TIMESTR=20130316-145809") in new stack [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:19] Set("SIP/fpbx-1-2e4ea3ce-00000009", "__FROMEXTEN=3045551234") in new stack [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:20] Set("SIP/fpbx-1-2e4ea3ce-00000009", "__CALLFILENAME=rg-600-3045551234-20130316-145809-1363460289.9") in new stack [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:21] Goto("SIP/fpbx-1-2e4ea3ce-00000009", "rg,1") in new stack [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Goto (sub-record-check,rg,1) [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:1] GosubIf("SIP/fpbx-1-2e4ea3ce-00000009", "0?record,1(rg,dontcare,3045551234)") in new stack [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:2] Return("SIP/fpbx-1-2e4ea3ce-00000009", "") in new stack [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:12] Set("SIP/fpbx-1-2e4ea3ce-00000009", "RingGroupMethod=ringall") in new stack [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:13] Macro("SIP/fpbx-1-2e4ea3ce-00000009", "dial,20,tr,101-103-105-106") in new stack [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:1] GotoIf("SIP/fpbx-1-2e4ea3ce-00000009", "1?dial") in new stack [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Goto (macro-dial,s,3) [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:3] AGI("SIP/fpbx-1-2e4ea3ce-00000009", "dialparties.agi") in new stack [2013-03-16 14:58:09] VERBOSE[11701] res_agi.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi [2013-03-16 14:58:09] VERBOSE[11701] res_agi.c: dialparties.agi: Starting New Dialparties.agi [2013-03-16 14:58:09] VERBOSE[11701] res_agi.c: dialparties.agi: Caller ID name is '+13045551234' number is '3045551234' [2013-03-16 14:58:09] VERBOSE[11701] res_agi.c: > dialparties.agi: USE_CONFIRMATION: 'FALSE' [2013-03-16 14:58:09] VERBOSE[11701] res_agi.c: > dialparties.agi: RINGGROUP_INDEX: '' [2013-03-16 14:58:09] VERBOSE[11701] res_agi.c: dialparties.agi: Methodology of ring is 'ringall' [2013-03-16 14:58:09] VERBOSE[11701] res_agi.c: -- dialparties.agi: Added extension 101 to extension map [2013-03-16 14:58:09] VERBOSE[11701] res_agi.c: -- dialparties.agi: Added extension 103 to extension map [2013-03-16 14:58:09] VERBOSE[11701] res_agi.c: -- dialparties.agi: Added extension 105 to extension map [2013-03-16 14:58:09] VERBOSE[11701] res_agi.c: -- dialparties.agi: Added extension 106 to extension map [2013-03-16 14:58:09] VERBOSE[11701] res_agi.c: -- dialparties.agi: Extension 101 cf is disabled [2013-03-16 14:58:09] VERBOSE[11701] res_agi.c: -- dialparties.agi: Extension 103 cf is disabled [2013-03-16 14:58:09] VERBOSE[11701] res_agi.c: -- dialparties.agi: Extension 105 cf is disabled [2013-03-16 14:58:09] VERBOSE[11701] res_agi.c: -- dialparties.agi: Extension 106 cf is disabled [2013-03-16 14:58:09] VERBOSE[11701] res_agi.c: -- dialparties.agi: Extension 101 do not disturb is disabled [2013-03-16 14:58:09] VERBOSE[11701] res_agi.c: -- dialparties.agi: Extension 103 do not disturb is disabled [2013-03-16 14:58:09] VERBOSE[11701] res_agi.c: -- dialparties.agi: Extension 105 do not disturb is disabled [2013-03-16 14:58:09] VERBOSE[11701] res_agi.c: -- dialparties.agi: Extension 106 do not disturb is disabled [2013-03-16 14:58:09] VERBOSE[11701] res_agi.c: > dialparties.agi: extnum 101 has: cw: 0; hascfb: 0 [] hascfu: 0 [] [2013-03-16 14:58:09] VERBOSE[11701] res_agi.c: dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE) [2013-03-16 14:58:09] VERBOSE[11701] res_agi.c: dialparties.agi: Extension 101 has ExtensionState: 0 [2013-03-16 14:58:09] VERBOSE[11701] res_agi.c: -- dialparties.agi: Checking CW and CFB status for extension 101 [2013-03-16 14:58:09] VERBOSE[11701] res_agi.c: -- dialparties.agi: dbset CALLTRACE/101 to 3045551234 [2013-03-16 14:58:09] VERBOSE[11701] res_agi.c: > dialparties.agi: extnum 103 has: cw: 0; hascfb: 0 [] hascfu: 0 [] [2013-03-16 14:58:09] VERBOSE[11701] res_agi.c: dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE) [2013-03-16 14:58:09] VERBOSE[11701] res_agi.c: dialparties.agi: Extension 103 has ExtensionState: 0 [2013-03-16 14:58:09] VERBOSE[11701] res_agi.c: -- dialparties.agi: Checking CW and CFB status for extension 103 [2013-03-16 14:58:09] VERBOSE[11701] res_agi.c: -- dialparties.agi: dbset CALLTRACE/103 to 3045551234 [2013-03-16 14:58:09] VERBOSE[11701] res_agi.c: > dialparties.agi: extnum 105 has: cw: 0; hascfb: 0 [] hascfu: 0 [] [2013-03-16 14:58:09] VERBOSE[11701] res_agi.c: dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE) [2013-03-16 14:58:09] VERBOSE[11701] res_agi.c: dialparties.agi: Extension 105 has ExtensionState: 0 [2013-03-16 14:58:09] VERBOSE[11701] res_agi.c: -- dialparties.agi: Checking CW and CFB status for extension 105 [2013-03-16 14:58:09] VERBOSE[11701] res_agi.c: -- dialparties.agi: dbset CALLTRACE/105 to 3045551234 [2013-03-16 14:58:09] VERBOSE[11701] res_agi.c: > dialparties.agi: extnum 106 has: cw: 0; hascfb: 0 [] hascfu: 0 [] [2013-03-16 14:58:09] VERBOSE[11701] res_agi.c: dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE) [2013-03-16 14:58:09] VERBOSE[11701] res_agi.c: dialparties.agi: Extension 106 has ExtensionState: 0 [2013-03-16 14:58:09] VERBOSE[11701] res_agi.c: -- dialparties.agi: Checking CW and CFB status for extension 106 [2013-03-16 14:58:09] VERBOSE[11701] res_agi.c: -- dialparties.agi: dbset CALLTRACE/106 to 3045551234 [2013-03-16 14:58:09] VERBOSE[11701] res_agi.c: -- dialparties.agi: Filtered ARG3: 101-103-105-106 [2013-03-16 14:58:09] VERBOSE[11701] res_agi.c: > dialparties.agi: NODEST: 600 adding M(auto-blkvm) to dialopts: trM(auto-blkvm) [2013-03-16 14:58:09] VERBOSE[11701] res_agi.c: > dialparties.agi: NODEST: 600 blkvm enabled macro already in dialopts: trM(auto-blkvm) [2013-03-16 14:58:09] VERBOSE[11701] res_agi.c: -- AGI Script dialparties.agi completed, returning 0 [2013-03-16 14:58:09] VERBOSE[11701] pbx.c: -- Executing [[email protected]:7] Dial("SIP/fpbx-1-2e4ea3ce-00000009", "SIP/101&SIP/103&SIP/105&SIP/106,20,trM(auto-blkvm)") in new stack [2013-03-16 14:58:09] VERBOSE[11701] netsock.c: == Using UDPTL TOS bits 184 [2013-03-16 14:58:09] VERBOSE[11701] netsock.c: == Using UDPTL CoS mark 5 [2013-03-16 14:58:09] VERBOSE[11701] netsock2.c: == Using SIP RTP TOS bits 184 [2013-03-16 14:58:09] VERBOSE[11701] netsock2.c: == Using SIP RTP CoS mark 5 [2013-03-16 14:58:09] VERBOSE[11701] app_dial.c: -- Called SIP/101 [2013-03-16 14:58:09] VERBOSE[11701] netsock.c: == Using UDPTL TOS bits 184 [2013-03-16 14:58:09] VERBOSE[11701] netsock.c: == Using UDPTL CoS mark 5 [2013-03-16 14:58:09] VERBOSE[11701] netsock2.c: == Using SIP RTP TOS bits 184 [2013-03-16 14:58:09] VERBOSE[11701] netsock2.c: == Using SIP RTP CoS mark 5 [2013-03-16 14:58:09] VERBOSE[11701] app_dial.c: -- Called SIP/103 [2013-03-16 14:58:09] VERBOSE[11701] netsock.c: == Using UDPTL TOS bits 184 [2013-03-16 14:58:09] VERBOSE[11701] netsock.c: == Using UDPTL CoS mark 5 [2013-03-16 14:58:09] VERBOSE[11701] netsock2.c: == Using SIP RTP TOS bits 184 [2013-03-16 14:58:09] VERBOSE[11701] netsock2.c: == Using SIP RTP CoS mark 5 [2013-03-16 14:58:09] VERBOSE[11701] app_dial.c: -- Called SIP/105 [2013-03-16 14:58:09] VERBOSE[11701] netsock.c: == Using UDPTL TOS bits 184 [2013-03-16 14:58:09] VERBOSE[11701] netsock.c: == Using UDPTL CoS mark 5 [2013-03-16 14:58:09] VERBOSE[11701] netsock2.c: == Using SIP RTP TOS bits 184 [2013-03-16 14:58:09] VERBOSE[11701] netsock2.c: == Using SIP RTP CoS mark 5 [2013-03-16 14:58:09] VERBOSE[11701] app_dial.c: -- Called SIP/106 [2013-03-16 14:58:09] VERBOSE[11701] app_dial.c: -- SIP/101-0000000a connected line has changed. Saving it until answer for SIP/fpbx-1-2e4ea3ce-00000009 [2013-03-16 14:58:09] VERBOSE[11701] app_dial.c: -- SIP/103-0000000b connected line has changed. Saving it until answer for SIP/fpbx-1-2e4ea3ce-00000009 [2013-03-16 14:58:09] VERBOSE[11701] app_dial.c: -- SIP/105-0000000c connected line has changed. Saving it until answer for SIP/fpbx-1-2e4ea3ce-00000009 [2013-03-16 14:58:09] VERBOSE[11701] app_dial.c: -- SIP/106-0000000d connected line has changed. Saving it until answer for SIP/fpbx-1-2e4ea3ce-00000009 [2013-03-16 14:58:09] VERBOSE[11701] app_dial.c: -- SIP/105-0000000c is ringing [2013-03-16 14:58:09] VERBOSE[11701] app_dial.c: -- SIP/106-0000000d is ringing [2013-03-16 14:58:09] VERBOSE[11701] app_dial.c: -- SIP/101-0000000a is ringing [2013-03-16 14:58:09] VERBOSE[11701] app_dial.c: -- SIP/103-0000000b is ringing [2013-03-16 14:58:11] VERBOSE[11701] app_macro.c: == Spawn extension (macro-dial, s, 7) exited non-zero on 'SIP/fpbx-1-2e4ea3ce-00000009' in macro 'dial' [2013-03-16 14:58:11] VERBOSE[11701] pbx.c: == Spawn extension (ext-group, 600, 13) exited non-zero on 'SIP/fpbx-1-2e4ea3ce-00000009' [2013-03-16 14:58:11] VERBOSE[11701] pbx.c: -- Executing [[email protected]:1] Macro("SIP/fpbx-1-2e4ea3ce-00000009", "hangupcall,") in new stack [2013-03-16 14:58:11] VERBOSE[11701] pbx.c: -- Executing [[email protected]:1] GotoIf("SIP/fpbx-1-2e4ea3ce-00000009", "1?theend") in new stack [2013-03-16 14:58:11] VERBOSE[11701] pbx.c: -- Goto (macro-hangupcall,s,3) [2013-03-16 14:58:11] VERBOSE[11701] pbx.c: -- Executing [[email protected]:3] ExecIf("SIP/fpbx-1-2e4ea3ce-00000009", "0?Set(CDR(recordingfile)=)") in new stack [2013-03-16 14:58:11] VERBOSE[11701] pbx.c: -- Executing [[email protected]:4] Hangup("SIP/fpbx-1-2e4ea3ce-00000009", "") in new stack [2013-03-16 14:58:11] VERBOSE[11701] app_macro.c: == Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'SIP/fpbx-1-2e4ea3ce-00000009' in macro 'hangupcall' [2013-03-16 14:58:11] VERBOSE[11701] pbx.c: == Spawn extension (ext-group, h, 1) exited non-zero on 'SIP/fpbx-1-2e4ea3ce-00000009' [2013-03-16 14:58:15] VERBOSE[11654] asterisk.c: -- Remote UNIX connection disconnected

Anyone help? please…

Time to erase the drive, I can’t find the problem, so ultimately I have no choice. Just want to thank SkyKing for his help in this matter…

Reloaded with a fresh, brand new version of FPBX and STILL NO CNAM. Seeing as I was told it was not sipstation, I kind of said fine, must be something on my server. Turns out it is not, and I have lost everything I had to make this happen.

Why didn’t you run a backup?

You never did post the SIP traces. I think you really jumped the gun. I could see installing on another machine or HD.

My last version was a build from a beta release, I ran a backup but could not locate where it went too. With very limited knowledge of linux, I was not able to find it, besides, I am starting a new job tomorrow and wanted to get this sorted and out of the way.

The stuff I lost, I can rebuild that as I go, I am just so disappointed that this issue still persists.

I have many backups my server used to output to my remote FTP server on a daily basis, I don’t think I need them right now, but may need them to rebuild my IVR’s and Voice prompts…lol.

So I have a brand new setup, working as well as before but with the newer CentOS and a shiny GUI…lol. I have rebuilt my routes, rebuilt my extensions and have my Cisco phones back up and running.

All I have to figure out is the iSymphony setup… and still find out why my CNAM is not coming through.

Until you send us the SIP trace from an inbound call (and please don’t send 500 lines before and after, I just want the invite) I can’t help you any further.

You will have to edit out the carats, they don’t display right.

an invite will look like this:

Reliably Transmitting (NAT) to 192.168.8.81:2048:
OPTIONS sip:[email protected]:2048;line=wlyeo5bw SIP/2.0
Via: SIP/2.0/UDP 192.168.8.8:5060;branch=z9hG4bK20e0015b;rport
Max-Forwards: 70
From: "Unknown" <sip:[email protected]>;tag=as12ed4e60
To: sip:[email protected]:2048;line=wlyeo5bw
Contact: sip:[email protected]:5060
Call-ID: [email protected]:5060
CSeq: 102 OPTIONS
User-Agent: FPBX-2.8.1(1.8.20.1)
Date: Sun, 17 Mar 2013 20:10:03 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Content-Length: 0