Inbound call issues - The person at extension xxx is unavailable

I have been assisting configuring a FreePBX setup. Previous to me, somebody in IT has used SIPStation to setup a trial to prove out creating extensions/outbound/inbound calls. Now that the trial has expired, I was tasked with getting one DID up and setting up the trunk. We have the DID through VoipInnovations. I was able to quickly get outgoing calls working, but I have been struggling with Incoming calls.

Long story short, after many configuration changes, I can now call my DID and I get the message “The person at extension xxx is unavailable.” I just figured out how to to the Asterisk CLI, but the log is rather complicated to understand without knowing exactly what to look for.

In addition, I can’t call from one extension to another.

At Asterisk CLI I typed ‘core set verbose 5’ and then I dialed ext 272. Here is the log from that…

Console verbose was OFF and is now 5.
== Setting global variable ‘SIPDOMAIN’ to ‘MYSERVER’
– Executing [[email protected]:1] GotoIf(“PJSIP/787-00000014”, “1?ext-local,272,1:followme-check,272,1”) in new stack
– Goto (ext-local,272,1)
– Executing [[email protected]:1] Set(“PJSIP/787-00000014”, “__RINGTIMER=15”) in new stack
– Executing [[email protected]:2] Macro(“PJSIP/787-00000014”, “exten-vm,novm,272,0,0,1”) in new stack
– Executing [[email protected]:1] Macro(“PJSIP/787-00000014”, “user-callerid,”) in new stack
– Executing [[email protected]:1] Set(“PJSIP/787-00000014”, “TOUCH_MONITOR=1503086071.21”) in new stack
– Executing [[email protected]:2] Set(“PJSIP/787-00000014”, “AMPUSER=787”) in new stack
– Executing [[email protected]:3] GotoIf(“PJSIP/787-00000014”, “0?report”) in new stack
– Executing [[email protected]:4] ExecIf(“PJSIP/787-00000014”, “1?Set(__REALCALLERIDNUM=787)”) in new stack
– Executing [[email protected]:5] Set(“PJSIP/787-00000014”, “AMPUSER=787”) in new stack
– Executing [[email protected]:6] GotoIf(“PJSIP/787-00000014”, “0?limit”) in new stack
– Executing [[email protected]:7] Set(“PJSIP/787-00000014”, “AMPUSERCIDNAME=Extension 787”) in new stack
– Executing [[email protected]:8] GotoIf(“PJSIP/787-00000014”, “0?report”) in new stack
– Executing [[email protected]:9] Set(“PJSIP/787-00000014”, “AMPUSERCID=787”) in new stack
– Executing [[email protected]:10] Set(“PJSIP/787-00000014”, “__DIAL_OPTIONS=Ttr”) in new stack
– Executing [[email protected]:11] Set(“PJSIP/787-00000014”, “CALLERID(all)=“Extension 787” <787>”) in new stack
– Executing [[email protected]:12] GotoIf(“PJSIP/787-00000014”, “0?limit”) in new stack
– Executing [[email protected]:13] ExecIf(“PJSIP/787-00000014”, “0?Set(GROUP(concurrency_limit)=787)”) in new stack
– Executing [[email protected]:14] ExecIf(“PJSIP/787-00000014”, “0?Set(CHANNEL(language)=)”) in new stack
– Executing [[email protected]:15] GotoIf(“PJSIP/787-00000014”, “0?continue”) in new stack
– Executing [[email protected]:16] ExecIf(“PJSIP/787-00000014”, “1?Set(__CALLEE_ACCOUNCODE=)”) in new stack
– Executing [[email protected]:17] Set(“PJSIP/787-00000014”, “__TTL=6”) in new stack
– Executing [[email protected]:18] GotoIf(“PJSIP/787-00000014”, “1?continue”) in new stack
– Goto (macro-user-callerid,s,29)
– Executing [[email protected]:29] Set(“PJSIP/787-00000014”, “CALLERID(number)=787”) in new stack
– Executing [[email protected]:30] Set(“PJSIP/787-00000014”, “CALLERID(name)=Extension 787”) in new stack
– Executing [[email protected]:31] GotoIf(“PJSIP/787-00000014”, “0?cnum”) in new stack
– Executing [[email protected]:32] Set(“PJSIP/787-00000014”, “CDR(cnam)=Extension 787”) in new stack
– Executing [[email protected]:33] Set(“PJSIP/787-00000014”, “CDR(cnum)=787”) in new stack
– Executing [[email protected]:34] Set(“PJSIP/787-00000014”, “CHANNEL(language)=en”) in new stack
– Executing [[email protected]:2] Set(“PJSIP/787-00000014”, “RingGroupMethod=none”) in new stack
– Executing [[email protected]:3] Set(“PJSIP/787-00000014”, “__EXTTOCALL=272”) in new stack
– Executing [[email protected]:4] Set(“PJSIP/787-00000014”, “__PICKUPMARK=272”) in new stack
– Executing [[email protected]:5] Set(“PJSIP/787-00000014”, “RT=15”) in new stack
[2017-08-18 15:54:31] WARNING[30366][C-00000014]: chan_sip.c:22955 func_header_read: This function can only be used on SIP channels.
– Executing [[email protected]:6] ExecIf(“PJSIP/787-00000014”, “0?Macro(vm,novm,DIRECTDIAL,)”) in new stack
[2017-08-18 15:54:31] WARNING[30366][C-00000014]: chan_sip.c:22955 func_header_read: This function can only be used on SIP channels.
[2017-08-18 15:54:31] WARNING[30366][C-00000014]: chan_sip.c:22955 func_header_read: This function can only be used on SIP channels.
– Executing [[email protected]:7] ExecIf(“PJSIP/787-00000014”, “0?MacroExit()”) in new stack
[2017-08-18 15:54:31] WARNING[30366][C-00000014]: chan_sip.c:22955 func_header_read: This function can only be used on SIP channels.
[2017-08-18 15:54:31] WARNING[30366][C-00000014]: chan_sip.c:22955 func_header_read: This function can only be used on SIP channels.
– Executing [[email protected]:8] ExecIf(“PJSIP/787-00000014”, “0?Gosub(ext-intercom,*80272,1())”) in new stack
[2017-08-18 15:54:31] WARNING[30366][C-00000014]: chan_sip.c:22955 func_header_read: This function can only be used on SIP channels.
[2017-08-18 15:54:31] WARNING[30366][C-00000014]: chan_sip.c:22955 func_header_read: This function can only be used on SIP channels.
– Executing [[email protected]:9] ExecIf(“PJSIP/787-00000014”, “0?MacroExit()”) in new stack
[2017-08-18 15:54:31] WARNING[30366][C-00000014]: chan_sip.c:22955 func_header_read: This function can only be used on SIP channels.
[2017-08-18 15:54:31] WARNING[30366][C-00000014]: chan_sip.c:22955 func_header_read: This function can only be used on SIP channels.
[2017-08-18 15:54:31] WARNING[30366][C-00000014]: pbx_functions.c:460 func_args: Can’t find trailing parenthesis for function ‘DB(DEVICE/272/dial’?
– Executing [[email protected]:10] ExecIf(“PJSIP/787-00000014”, “0?ChanSpy(PJSIP/272,q)”) in new stack
[2017-08-18 15:54:31] WARNING[30366][C-00000014]: chan_sip.c:22955 func_header_read: This function can only be used on SIP channels.
[2017-08-18 15:54:31] WARNING[30366][C-00000014]: pbx_functions.c:460 func_args: Can’t find trailing parenthesis for function ‘DB(DEVICE/272/dial’?
[2017-08-18 15:54:31] WARNING[30366][C-00000014]: chan_sip.c:22955 func_header_read: This function can only be used on SIP channels.
– Executing [[email protected]:11] ExecIf(“PJSIP/787-00000014”, “0?MacroExit()”) in new stack
[2017-08-18 15:54:31] WARNING[30366][C-00000014]: chan_sip.c:22955 func_header_read: This function can only be used on SIP channels.
– Executing [[email protected]:12] Gosub(“PJSIP/787-00000014”, “sub-record-check,s,1(exten,272,dontcare)”) in new stack
– Executing [[email protected]:1] GotoIf(“PJSIP/787-00000014”, “0?initialized”) in new stack
– Executing [[email protected]:2] Set(“PJSIP/787-00000014”, “__REC_STATUS=INITIALIZED”) in new stack
– Executing [[email protected]:3] Set(“PJSIP/787-00000014”, “NOW=1503086071”) in new stack
– Executing [[email protected]:4] Set(“PJSIP/787-00000014”, “__DAY=18”) in new stack
– Executing [[email protected]:5] Set(“PJSIP/787-00000014”, “__MONTH=08”) in new stack
– Executing [[email protected]:6] Set(“PJSIP/787-00000014”, “__YEAR=2017”) in new stack
– Executing [[email protected]:7] Set(“PJSIP/787-00000014”, “__TIMESTR=20170818-155431”) in new stack
– Executing [[email protected]:8] Set(“PJSIP/787-00000014”, “__FROMEXTEN=787”) in new stack
– Executing [[email protected]:9] Set(“PJSIP/787-00000014”, “__MON_FMT=wav”) in new stack
– Executing [[email protected]:10] NoOp(“PJSIP/787-00000014”, “Recordings initialized”) in new stack
– Executing [[email protected]:11] ExecIf(“PJSIP/787-00000014”, “0?Set(ARG3=dontcare)”) in new stack
– Executing [[email protected]:12] Set(“PJSIP/787-00000014”, “REC_POLICY_MODE_SAVE=”) in new stack
– Executing [[email protected]:13] ExecIf(“PJSIP/787-00000014”, “0?Set(REC_STATUS=NO)”) in new stack
– Executing [[email protected]:14] GotoIf(“PJSIP/787-00000014”, “5?checkaction”) in new stack
– Goto (sub-record-check,s,17)
– Executing [[email protected]:17] GotoIf(“PJSIP/787-00000014”, “1?sub-record-check,exten,1”) in new stack
– Goto (sub-record-check,exten,1)
– Executing [[email protected]:1] NoOp(“PJSIP/787-00000014”, “Exten Recording Check between 787 and 272”) in new stack
– Executing [[email protected]:2] Set(“PJSIP/787-00000014”, “CALLTYPE=internal”) in new stack
– Executing [[email protected]:3] ExecIf(“PJSIP/787-00000014”, “0?Set(CALLTYPE=)”) in new stack
– Executing [[email protected]:4] Set(“PJSIP/787-00000014”, “CALLEE=dontcare”) in new stack
– Executing [[email protected]:5] ExecIf(“PJSIP/787-00000014”, “0?Set(CALLEE=dontcare)”) in new stack
– Executing [[email protected]:6] GotoIf(“PJSIP/787-00000014”, “0?callee”) in new stack
– Executing [[email protected]:7] GotoIf(“PJSIP/787-00000014”, “1?caller”) in new stack
– Goto (sub-record-check,exten,13)
– Executing [[email protected]:13] Set(“PJSIP/787-00000014”, “RECMODE=dontcare”) in new stack
– Executing [[email protected]:14] ExecIf(“PJSIP/787-00000014”, “0?Set(RECMODE=dontcare)”) in new stack
– Executing [[email protected]:15] ExecIf(“PJSIP/787-00000014”, “1?Set(RECMODE=dontcare)”) in new stack
– Executing [[email protected]:16] Gosub(“PJSIP/787-00000014”, “recordcheck,1(dontcare,internal,272)”) in new stack
– Executing [[email protected]:1] NoOp(“PJSIP/787-00000014”, “Starting recording check against dontcare”) in new stack
– Executing [[email protected]:2] Goto(“PJSIP/787-00000014”, “dontcare”) in new stack
– Goto (sub-record-check,recordcheck,3)
– Executing [[email protected]:3] Return(“PJSIP/787-00000014”, “”) in new stack
– Executing [[email protected]:17] Return(“PJSIP/787-00000014”, “”) in new stack
– Executing [[email protected]:13] GotoIf(“PJSIP/787-00000014”, “1?macrodial”) in new stack
– Goto (macro-exten-vm,s,19)
– Executing [[email protected]:19] GosubIf(“PJSIP/787-00000014”, “0?clrheader,1()”) in new stack
– Executing [[email protected]:20] Macro(“PJSIP/787-00000014”, “dial-one,15,Ttr,272”) in new stack
– Executing [[email protected]:1] Set(“PJSIP/787-00000014”, “DEXTEN=272”) in new stack
– Executing [[email protected]:2] Set(“PJSIP/787-00000014”, “__CRM_SOURCE=787”) in new stack
– Executing [[email protected]:3] ExecIf(“PJSIP/787-00000014”, “0?Set(EXTTOCALL=272)”) in new stack
– Executing [[email protected]:4] Set(“PJSIP/787-00000014”, “DIALSTATUS_CW=”) in new stack
– Executing [[email protected]:5] GosubIf(“PJSIP/787-00000014”, “0?screen,1()”) in new stack
– Executing [[email protected]:6] GosubIf(“PJSIP/787-00000014”, “0?cf,1()”) in new stack
– Executing [[email protected]:7] GotoIf(“PJSIP/787-00000014”, “1?skip1”) in new stack
– Goto (macro-dial-one,s,10)
– Executing [[email protected]:10] GotoIf(“PJSIP/787-00000014”, “0?nodial”) in new stack
– Executing [[email protected]:11] GotoIf(“PJSIP/787-00000014”, “0?continue”) in new stack
– Executing [[email protected]:12] Set(“PJSIP/787-00000014”, “EXTHASCW=ENABLED”) in new stack
– Executing [[email protected]:13] GotoIf(“PJSIP/787-00000014”, “0?next1:cwinusebusy”) in new stack
– Goto (macro-dial-one,s,25)
– Executing [[email protected]:25] GotoIf(“PJSIP/787-00000014”, “0?next3:continue”) in new stack
– Goto (macro-dial-one,s,27)
– Executing [[email protected]:27] GotoIf(“PJSIP/787-00000014”, “0?nodial”) in new stack
– Executing [[email protected]:28] GosubIf(“PJSIP/787-00000014”, “1?dstring,1():dlocal,1()”) in new stack
– Executing [[email protected]:1] Set(“PJSIP/787-00000014”, “DSTRING=”) in new stack
– Executing [[email protected]:2] Set(“PJSIP/787-00000014”, “DEVICES=99272&272”) in new stack
– Executing [[email protected]:3] ExecIf(“PJSIP/787-00000014”, “0?Return()”) in new stack
– Executing [[email protected]:4] ExecIf(“PJSIP/787-00000014”, “0?Set(DEVICES=9272&272)”) in new stack
– Executing [[email protected]:5] Set(“PJSIP/787-00000014”, “LOOPCNT=2”) in new stack
– Executing [[email protected]:6] Set(“PJSIP/787-00000014”, “ITER=1”) in new stack
– Executing [[email protected]:7] Set(“PJSIP/787-00000014”, “THISDIAL=SIP/99272”) in new stack
– Executing [[email protected]:8] GosubIf(“PJSIP/787-00000014”, “1?zap2dahdi,1()”) in new stack
– Executing [[email protected]:1] ExecIf(“PJSIP/787-00000014”, “0?Return()”) in new stack
– Executing [[email protected]:2] Set(“PJSIP/787-00000014”, “NEWDIAL=”) in new stack
– Executing [[email protected]:3] Set(“PJSIP/787-00000014”, “LOOPCNT2=1”) in new stack
– Executing [[email protected]:4] Set(“PJSIP/787-00000014”, “ITER2=1”) in new stack
– Executing [[email protected]:5] Set(“PJSIP/787-00000014”, “THISPART2=SIP/99272”) in new stack
– Executing [[email protected]:6] ExecIf(“PJSIP/787-00000014”, “0?Set(THISPART2=DAHDI/99272)”) in new stack
– Executing [[email protected]:7] Set(“PJSIP/787-00000014”, “NEWDIAL=SIP/99272&”) in new stack
– Executing [[email protected]:8] Set(“PJSIP/787-00000014”, “ITER2=2”) in new stack
– Executing [[email protected]:9] GotoIf(“PJSIP/787-00000014”, “0?begin2”) in new stack
– Executing [[email protected]:10] Set(“PJSIP/787-00000014”, “THISDIAL=SIP/99272”) in new stack
– Executing [[email protected]:11] Return(“PJSIP/787-00000014”, “”) in new stack
– Executing [[email protected]:9] GotoIf(“PJSIP/787-00000014”, “1?docheck”) in new stack
– Goto (macro-dial-one,dstring,14)
– Executing [[email protected]:14] GotoIf(“PJSIP/787-00000014”, “0?skipset”) in new stack
– Executing [[email protected]:15] Set(“PJSIP/787-00000014”, “DSTRING=SIP/99272&”) in new stack
– Executing [[email protected]:16] Set(“PJSIP/787-00000014”, “ITER=2”) in new stack
– Executing [[email protected]:17] GotoIf(“PJSIP/787-00000014”, “1?begin”) in new stack
– Goto (macro-dial-one,dstring,7)
– Executing [[email protected]:7] Set(“PJSIP/787-00000014”, “THISDIAL=PJSIP/272”) in new stack
– Executing [[email protected]:8] GosubIf(“PJSIP/787-00000014”, “1?zap2dahdi,1()”) in new stack
– Executing [[email protected]:1] ExecIf(“PJSIP/787-00000014”, “0?Return()”) in new stack
– Executing [[email protected]:2] Set(“PJSIP/787-00000014”, “NEWDIAL=”) in new stack
– Executing [[email protected]:3] Set(“PJSIP/787-00000014”, “LOOPCNT2=1”) in new stack
– Executing [[email protected]:4] Set(“PJSIP/787-00000014”, “ITER2=1”) in new stack
– Executing [[email protected]:5] Set(“PJSIP/787-00000014”, “THISPART2=PJSIP/272”) in new stack
– Executing [[email protected]:6] ExecIf(“PJSIP/787-00000014”, “0?Set(THISPART2=DAHDIIP/272)”) in new stack
– Executing [[email protected]:7] Set(“PJSIP/787-00000014”, “NEWDIAL=PJSIP/272&”) in new stack
– Executing [[email protected]:8] Set(“PJSIP/787-00000014”, “ITER2=2”) in new stack
– Executing [[email protected]:9] GotoIf(“PJSIP/787-00000014”, “0?begin2”) in new stack
– Executing [[email protected]:10] Set(“PJSIP/787-00000014”, “THISDIAL=PJSIP/272”) in new stack
– Executing [[email protected]:11] Return(“PJSIP/787-00000014”, “”) in new stack
– Executing [[email protected]:9] GotoIf(“PJSIP/787-00000014”, “0?docheck”) in new stack
– Executing [[email protected]:10] NoOp(“PJSIP/787-00000014”, “Debug: Found PJSIP Destination PJSIP/272”) in new stack
– Executing [[email protected]:11] GotoIf(“PJSIP/787-00000014”, “0?doset”) in new stack
– Executing [[email protected]:12] NoOp(“PJSIP/787-00000014”, “Debug: Updating PJSIP Destination with PJSIP_DIAL_CONTACTS”) in new stack
– Executing [[email protected]:13] Set(“PJSIP/787-00000014”, “THISDIAL=”) in new stack
– Executing [[email protected]:14] GotoIf(“PJSIP/787-00000014”, “1?skipset”) in new stack
– Goto (macro-dial-one,dstring,16)
– Executing [[email protected]:16] Set(“PJSIP/787-00000014”, “ITER=3”) in new stack
– Executing [[email protected]:17] GotoIf(“PJSIP/787-00000014”, “0?begin”) in new stack
– Executing [[email protected]:18] ExecIf(“PJSIP/787-00000014”, “0?Return()”) in new stack
– Executing [[email protected]:19] Set(“PJSIP/787-00000014”, “DSTRING=SIP/99272”) in new stack
– Executing [[email protected]:20] Return(“PJSIP/787-00000014”, “”) in new stack
– Executing [[email protected]:29] GotoIf(“PJSIP/787-00000014”, “0?nodial”) in new stack
– Executing [[email protected]:30] GotoIf(“PJSIP/787-00000014”, “0?skiptrace”) in new stack
– Executing [[email protected]:31] GosubIf(“PJSIP/787-00000014”, “1?ctset,1():ctclear,1()”) in new stack
– Executing [[email protected]:1] Set(“PJSIP/787-00000014”, “DB(CALLTRACE/272)=787”) in new stack
– Executing [[email protected]:2] Return(“PJSIP/787-00000014”, “”) in new stack
– Executing [[email protected]:32] Set(“PJSIP/787-00000014”, “D_OPTIONS=Ttr”) in new stack
– Executing [[email protected]:33] NoOp(“PJSIP/787-00000014”, "Blind Transfer: , Attended Transfer: , User: 787, Alert Info: ") in new stack
– Executing [[email protected]:34] ExecIf(“PJSIP/787-00000014”, “1?Set(ALERT_INFO=)”) in new stack
– Executing [[email protected]:35] ExecIf(“PJSIP/787-00000014”, “0?Set(ALERT_INFO=)”) in new stack
– Executing [[email protected]:36] ExecIf(“PJSIP/787-00000014”, “0?Set(ALERT_INFO=)”) in new stack
– Executing [[email protected]:37] ExecIf(“PJSIP/787-00000014”, “0?Set(ALERT_INFO=Normal;volume=)”) in new stack
– Executing [[email protected]:38] ExecIf(“PJSIP/787-00000014”, “0?Set(ALERT_INFO=Normal;volume=)”) in new stack
– Executing [[email protected]:39] GosubIf(“PJSIP/787-00000014”, “0?func-set-sipheader,s,1(Alert-Info,)”) in new stack
– Executing [[email protected]:40] ExecIf(“PJSIP/787-00000014”, “0?Set(CHANNEL(musicclass)=)”) in new stack
– Executing [[email protected]:41] GosubIf(“PJSIP/787-00000014”, “0?qwait,1()”) in new stack
– Executing [[email protected]:42] Set(“PJSIP/787-00000014”, “__CWIGNORE=”) in new stack
– Executing [[email protected]:43] Set(“PJSIP/787-00000014”, “__KEEPCID=TRUE”) in new stack
– Executing [[email protected]:44] GotoIf(“PJSIP/787-00000014”, “0?usegoto,1”) in new stack
– Executing [[email protected]:45] GotoIf(“PJSIP/787-00000014”, “0?godial”) in new stack
– Executing [[email protected]:46] Gosub(“PJSIP/787-00000014”, “sub-presencestate-display,s,1(272)”) in new stack
– Executing [[email protected]:1] Goto(“PJSIP/787-00000014”, “state-not_set,1”) in new stack
– Goto (sub-presencestate-display,state-not_set,1)
– Executing [[email protected]:1] Set(“PJSIP/787-00000014”, “PRESENCESTATE_DISPLAY=”) in new stack
– Executing [[email protected]:2] Return(“PJSIP/787-00000014”, “”) in new stack
– Executing [[email protected]:47] Set(“PJSIP/787-00000014”, “CONNECTEDLINE(name,i)=fname lname”) in new stack
– Executing [[email protected]:48] Set(“PJSIP/787-00000014”, “CONNECTEDLINE(num)=272”) in new stack
– Executing [[email protected]:49] Set(“PJSIP/787-00000014”, “D_OPTIONS=TtrI”) in new stack
– Executing [[email protected]:50] Macro(“PJSIP/787-00000014”, “dialout-one-predial-hook,”) in new stack
– Executing [[email protected]:1] MacroExit(“PJSIP/787-00000014”, “”) in new stack
– Executing [[email protected]:51] ExecIf(“PJSIP/787-00000014”, “0?Set(D_OPTIONS=trII)”) in new stack
– Executing [[email protected]:52] NoOp(“PJSIP/787-00000014”, “”) in new stack
[2017-08-18 15:54:31] WARNING[30366][C-00000014]: taskprocessor.c:888 taskprocessor_push: The ‘subm:ast_channel_topic_all-cached-00000064’ task processor queue reached 500 scheduled tasks again.
[2017-08-18 15:54:31] WARNING[30366][C-00000014]: taskprocessor.c:888 taskprocessor_push: The ‘subm:ast_channel_topic_all-cached-00000065’ task processor queue reached 500 scheduled tasks again.
[2017-08-18 15:54:31] WARNING[30366][C-00000014]: taskprocessor.c:888 taskprocessor_push: The ‘subm:ast_channel_topic_all-cached-00000067’ task processor queue reached 500 scheduled tasks again.
[2017-08-18 15:54:31] WARNING[30366][C-00000014]: taskprocessor.c:888 taskprocessor_push: The ‘subm:endpoint_topic_all-cached-00000008’ task processor queue reached 500 scheduled tasks again.
[2017-08-18 15:54:31] WARNING[30366][C-00000014]: taskprocessor.c:888 taskprocessor_push: The ‘subp:PJSIP/787-0000001e’ task processor queue reached 500 scheduled tasks.
[2017-08-18 15:54:31] WARNING[30366][C-00000014]: taskprocessor.c:888 taskprocessor_push: The ‘subm:endpoint_topic_all-cached-0000006b’ task processor queue reached 500 scheduled tasks.
– Executing [[email protected]:53] Dial(“PJSIP/787-00000014”, “SIP/99272,15,TtrIb(func-apply-sipheaders^s^1)”) in new stack
[2017-08-18 15:54:31] WARNING[30366][C-00000014]: app_dial.c:2525 dial_exec_full: Unable to create channel of type ‘SIP’ (cause 20 - Subscriber absent)
== Everyone is busy/congested at this time (1:0/0/1)
– Executing [[email protected]:54] ExecIf(“PJSIP/787-00000014”, “0?MacroExit()”) in new stack
[2017-08-18 15:54:31] WARNING[27938]: db.c:288 db_execute_sql: Error executing SQL (COMMIT): database is locked
– Executing [[email protected]:55] ExecIf(“PJSIP/787-00000014”, “0?Set(DIALSTATUS=)”) in new stack
– Executing [[email protected]:56] GosubIf(“PJSIP/787-00000014”, “0?s-CHANUNAVAIL,1()”) in new stack
– Executing [[email protected]:57] MacroExit(“PJSIP/787-00000014”, “”) in new stack
– Executing [[email protected]:21] Set(“PJSIP/787-00000014”, “SV_DIALSTATUS=CHANUNAVAIL”) in new stack
– Executing [[email protected]:22] GosubIf(“PJSIP/787-00000014”, “0?docfu,1()”) in new stack
– Executing [[email protected]:23] GosubIf(“PJSIP/787-00000014”, “0?docfb,1()”) in new stack
– Executing [[email protected]:24] Set(“PJSIP/787-00000014”, “DIALSTATUS=CHANUNAVAIL”) in new stack
– Executing [[email protected]:25] ExecIf(“PJSIP/787-00000014”, “1?MacroExit()”) in new stack
– Executing [[email protected]:3] Set(“PJSIP/787-00000014”, “__PICKUPMARK=”) in new stack
– Executing [[email protected]:4] GotoIf(“PJSIP/787-00000014”, “1?ext-local,vmu889,1”) in new stack
– Goto (ext-local,vmu889,1)
– Executing [[email protected]:1] Macro(“PJSIP/787-00000014”, “vm,889,NOANSWER,”) in new stack
– Executing [[email protected]:1] Macro(“PJSIP/787-00000014”, “user-callerid,SKIPTTL”) in new stack
– Executing [[email protected]:1] Set(“PJSIP/787-00000014”, “TOUCH_MONITOR=1503086071.21”) in new stack
– Executing [[email protected]:2] Set(“PJSIP/787-00000014”, “AMPUSER=787”) in new stack
– Executing [[email protected]:3] GotoIf(“PJSIP/787-00000014”, “13?report”) in new stack
– Goto (macro-user-callerid,s,15)
– Executing [[email protected]:15] GotoIf(“PJSIP/787-00000014”, “1?continue”) in new stack
– Goto (macro-user-callerid,s,29)
– Executing [[email protected]:29] Set(“PJSIP/787-00000014”, “CALLERID(number)=787”) in new stack
– Executing [[email protected]:30] Set(“PJSIP/787-00000014”, “CALLERID(name)=Extension 787”) in new stack
– Executing [[email protected]:31] GotoIf(“PJSIP/787-00000014”, “0?cnum”) in new stack
– Executing [[email protected]:32] Set(“PJSIP/787-00000014”, “CDR(cnam)=Extension 787”) in new stack
– Executing [[email protected]:33] Set(“PJSIP/787-00000014”, “CDR(cnum)=787”) in new stack
– Executing [[email protected]:34] Set(“PJSIP/787-00000014”, “CHANNEL(language)=en”) in new stack
– Executing [[email protected]:2] Set(“PJSIP/787-00000014”, “VMGAIN=”) in new stack
– Executing [[email protected]:3] Macro(“PJSIP/787-00000014”, “blkvm-check,”) in new stack
– Executing [[email protected]:1] Set(“PJSIP/787-00000014”, “GOSUB_RETVAL=”) in new stack
– Executing [[email protected]:2] ExecIf(“PJSIP/787-00000014”, “0?Set(GOSUB_RETVAL=TRUE)”) in new stack
– Executing [[email protected]:3] MacroExit(“PJSIP/787-00000014”, “”) in new stack
– Executing [[email protected]:4] GotoIf(“PJSIP/787-00000014”, “1?vmx,1”) in new stack
– Goto (macro-vm,vmx,1)
– Executing [[email protected]:1] Set(“PJSIP/787-00000014”, “__EXTTOCALL=889”) in new stack
– Executing [[email protected]:2] Set(“PJSIP/787-00000014”, “__CRM_VOICEMAIL=889”) in new stack
– Executing [[email protected]:3] Set(“PJSIP/787-00000014”, “MEXTEN=889”) in new stack
– Executing [[email protected]:4] Set(“PJSIP/787-00000014”, “MMODE=NOANSWER”) in new stack
– Executing [[email protected]:5] Set(“PJSIP/787-00000014”, “RETVM=”) in new stack
– Executing [[email protected]:6] Set(“PJSIP/787-00000014”, “MODE=unavail”) in new stack
– Executing [[email protected]:7] Macro(“PJSIP/787-00000014”, “get-vmcontext,889”) in new stack
– Executing [[email protected]:1] Set(“PJSIP/787-00000014”, “VMCONTEXT=default”) in new stack
– Executing [[email protected]:2] GotoIf(“PJSIP/787-00000014”, “0?200:300”) in new stack
– Goto (macro-get-vmcontext,s,300)
– Executing [[email protected]:300] NoOp(“PJSIP/787-00000014”, “”) in new stack
– Executing [[email protected]:8] Set(“PJSIP/787-00000014”, “MODE=unavail”) in new stack
– Executing [[email protected]:9] NoOp(“PJSIP/787-00000014”, “MODE IS: unavail”) in new stack
– Executing [[email protected]:10] GotoIf(“PJSIP/787-00000014”, “1?chknomsg”) in new stack
– Goto (macro-vm,vmx,12)
– Executing [[email protected]:12] GotoIf(“PJSIP/787-00000014”, “0?s-NOANSWER,1”) in new stack
– Executing [[email protected]:13] GotoIf(“PJSIP/787-00000014”, “1?notdirect”) in new stack
– Goto (macro-vm,vmx,15)
– Executing [[email protected]:15] NoOp(“PJSIP/787-00000014”, "Checking if ext 889 is enabled: ") in new stack
– Executing [[email protected]:16] GotoIf(“PJSIP/787-00000014”, “1?s-NOANSWER,1”) in new stack
– Goto (macro-vm,s-NOANSWER,1)
– Executing [[email protected]:1] Macro(“PJSIP/787-00000014”, “get-vmcontext,889”) in new stack
– Executing [[email protected]:1] Set(“PJSIP/787-00000014”, “VMCONTEXT=default”) in new stack
– Executing [[email protected]:2] GotoIf(“PJSIP/787-00000014”, “0?200:300”) in new stack
– Goto (macro-get-vmcontext,s,300)
– Executing [[email protected]:300] NoOp(“PJSIP/787-00000014”, “”) in new stack
– Executing [[email protected]:2] VoiceMail(“PJSIP/787-00000014”, “[email protected],u”) in new stack
> 0x7f50c0e10390 – Probation passed - setting RTP source address to 146.186.32.137:16388
[2017-08-18 15:54:31] WARNING[30366][C-00000014]: translate.c:487 ast_translator_build_path: No translator path: (ending codec is not valid)
– <PJSIP/787-00000014> Playing ‘vm-theperson.ulaw’ (language ‘en’)
[2017-08-18 15:54:33] WARNING[30366][C-00000014]: translate.c:487 ast_translator_build_path: No translator path: (ending codec is not valid)
– <PJSIP/787-00000014> Playing ‘digits/8.ulaw’ (language ‘en’)
[2017-08-18 15:54:33] WARNING[30366][C-00000014]: translate.c:487 ast_translator_build_path: No translator path: (ending codec is not valid)
[2017-08-18 15:54:33] WARNING[30366][C-00000014]: translate.c:487 ast_translator_build_path: No translator path: (ending codec is not valid)
– <PJSIP/787-00000014> Playing ‘digits/8.ulaw’ (language ‘en’)
[2017-08-18 15:54:33] WARNING[30366][C-00000014]: translate.c:487 ast_translator_build_path: No translator path: (ending codec is not valid)

-- <PJSIP/787-00000014> Playing 'digits/9.ulaw' (language 'en')

[2017-08-18 15:54:34] WARNING[30366][C-00000014]: translate.c:487 ast_translator_build_path: No translator path: (ending codec is not valid)

-- <PJSIP/787-00000014> Playing 'vm-isunavail.ulaw' (language 'en')

[2017-08-18 15:54:35] WARNING[30366][C-00000014]: translate.c:487 ast_translator_build_path: No translator path: (ending codec is not valid)
== Spawn extension (macro-vm, s-NOANSWER, 2) exited non-zero on ‘PJSIP/787-00000014’ in macro ‘vm’
== Spawn extension (ext-local, vmu889, 1) exited non-zero on ‘PJSIP/787-00000014’
– Executing [[email protected]:1] Macro(“PJSIP/787-00000014”, “hangupcall,”) in new stack
– Executing [[email protected]:1] GotoIf(“PJSIP/787-00000014”, “1?theend”) in new stack
– Goto (macro-hangupcall,s,3)
– Executing [[email protected]:3] ExecIf(“PJSIP/787-00000014”, “0?Set(CDR(recordingfile)=)”) in new stack
– Executing [[email protected]:4] Hangup(“PJSIP/787-00000014”, “”) in new stack
== Spawn extension (macro-hangupcall, s, 4) exited non-zero on ‘PJSIP/787-00000014’ in macro ‘hangupcall’
== Spawn extension (ext-local, h, 1) exited non-zero on 'PJSIP/787-00000014’
freepbx*CLI>

Any ideas? Any thing to check for inbound calls from outside?

If you can’t make internal calls, then your extensions are not registered with asterisk. Are the phones and the asterisk server on the same network?

Yes same network.

I’m reasonably certain this is a huge part of your problem. Try setting all of your extensions to use Ulaw instead of whatever codec your are using and try again.

Alright, got an update with good news. Unfortunately changes were made to the system, but luckily I had a copy of the trunk details that I had set up for outgoing calls. IT restored the system prior to the test trunks being deleted by the system. I deleted the test trunks and added the new one with my details. I then copied that trunk and switched the host to match up to another IP specified by Voip Innovations. Now inbound/outbound work and extensions work. Now I am moving on to trying to implement all of our required features. In the end, I am not sure exactly what happened, but I am happy to see that all calls are working now.