-- Executing [*805318@from-internal:1] Goto("Local/*805318@from-internal-00000099;2", "ext-intercom,*805318,1") in new stack
    -- Goto (ext-intercom,*805318,1)
    -- Executing [*805318@ext-intercom:1] Macro("Local/*805318@from-internal-00000099;2", "user-callerid,") in new stack
    -- Executing [s@macro-user-callerid:1] Set("Local/*805318@from-internal-00000099;2", "TOUCH_MONITOR=1422612144.2219") in new stack
    -- Executing [s@macro-user-callerid:2] Set("Local/*805318@from-internal-00000099;2", "AMPUSER=5318") in new stack
    -- Executing [s@macro-user-callerid:3] GotoIf("Local/*805318@from-internal-00000099;2", "0?report") in new stack
    -- Executing [s@macro-user-callerid:4] ExecIf("Local/*805318@from-internal-00000099;2", "1?Set(REALCALLERIDNUM=5318)") in new stack
    -- Executing [s@macro-user-callerid:5] Set("Local/*805318@from-internal-00000099;2", "AMPUSER=5318") in new stack
    -- Executing [s@macro-user-callerid:6] GotoIf("Local/*805318@from-internal-00000099;2", "0?limit") in new stack
    -- Executing [s@macro-user-callerid:7] Set("Local/*805318@from-internal-00000099;2", "AMPUSERCIDNAME=Johann Mobile") in new stack
    -- Executing [s@macro-user-callerid:8] GotoIf("Local/*805318@from-internal-00000099;2", "0?report") in new stack
    -- Executing [s@macro-user-callerid:9] Set("Local/*805318@from-internal-00000099;2", "AMPUSERCID=5314") in new stack
    -- Executing [s@macro-user-callerid:10] Set("Local/*805318@from-internal-00000099;2", "__DIAL_OPTIONS=Ttr") in new stack
    -- Executing [s@macro-user-callerid:11] Set("Local/*805318@from-internal-00000099;2", "CALLERID(all)="Johann Mobile" <5314>") in new stack
    -- Executing [s@macro-user-callerid:12] GotoIf("Local/*805318@from-internal-00000099;2", "0?limit") in new stack
    -- Executing [s@macro-user-callerid:13] ExecIf("Local/*805318@from-internal-00000099;2", "0?Set(GROUP(concurrency_limit)=5318)") in new stack
    -- Executing [s@macro-user-callerid:14] GosubIf("Local/*805318@from-internal-00000099;2", "7?sub-ccss,s,1(ext-intercom,)") in new stack
    -- Executing [s@sub-ccss:1] ExecIf("Local/*805318@from-internal-00000099;2", "0?Return()") in new stack
    -- Executing [s@sub-ccss:2] Set("Local/*805318@from-internal-00000099;2", "CCSS_SETUP=TRUE") in new stack
    -- Executing [s@sub-ccss:3] GosubIf("Local/*805318@from-internal-00000099;2", "0?monitor_config,1(ext-intercom,):monitor_default,1(ext-intercom,)") in new stack
    -- Executing [monitor_default@sub-ccss:1] GotoIf("Local/*805318@from-internal-00000099;2", "0?is_exten") in new stack
    -- Executing [monitor_default@sub-ccss:2] StackPop("Local/*805318@from-internal-00000099;2", "") in new stack
    -- Executing [monitor_default@sub-ccss:3] Return("Local/*805318@from-internal-00000099;2", "FALSE") in new stack
    -- Executing [s@macro-user-callerid:15] ExecIf("Local/*805318@from-internal-00000099;2", "1?Set(CHANNEL(language)=5313)") in new stack
    -- Executing [s@macro-user-callerid:16] GotoIf("Local/*805318@from-internal-00000099;2", "0?continue") in new stack
    -- Executing [s@macro-user-callerid:17] ExecIf("Local/*805318@from-internal-00000099;2", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
    -- Executing [s@macro-user-callerid:18] Set("Local/*805318@from-internal-00000099;2", "__TTL=64") in new stack
    -- Executing [s@macro-user-callerid:19] GotoIf("Local/*805318@from-internal-00000099;2", "1?continue") in new stack
    -- Goto (macro-user-callerid,s,30)
    -- Executing [s@macro-user-callerid:30] Set("Local/*805318@from-internal-00000099;2", "CALLERID(number)=5314") in new stack
    -- Executing [s@macro-user-callerid:31] Set("Local/*805318@from-internal-00000099;2", "CALLERID(name)=Johann Mobile") in new stack
    -- Executing [s@macro-user-callerid:32] Set("Local/*805318@from-internal-00000099;2", "CDR(cnum)=5314") in new stack
    -- Executing [s@macro-user-callerid:33] Set("Local/*805318@from-internal-00000099;2", "CDR(cnam)=Johann Mobile") in new stack
    -- Executing [s@macro-user-callerid:34] Set("Local/*805318@from-internal-00000099;2", "CHANNEL(language)=5313") in new stack
    -- Executing [*805318@ext-intercom:2] Set("Local/*805318@from-internal-00000099;2", "dialnumber=5318") in new stack
    -- Executing [*805318@ext-intercom:3] Set("Local/*805318@from-internal-00000099;2", "INTERCOM_CALL=TRUE") in new stack
    -- Executing [*805318@ext-intercom:4] Gosub("Local/*805318@from-internal-00000099;2", "sub-record-check,s,1(exten,5318)") in new stack
    -- Executing [s@sub-record-check:1] GotoIf("Local/*805318@from-internal-00000099;2", "0?initialized") in new stack
    -- Executing [s@sub-record-check:2] Set("Local/*805318@from-internal-00000099;2", "__REC_STATUS=INITIALIZED") in new stack
    -- Executing [s@sub-record-check:3] Set("Local/*805318@from-internal-00000099;2", "NOW=1422612144") in new stack
    -- Executing [s@sub-record-check:4] Set("Local/*805318@from-internal-00000099;2", "__DAY=30") in new stack
    -- Executing [s@sub-record-check:5] Set("Local/*805318@from-internal-00000099;2", "__MONTH=01") in new stack
    -- Executing [s@sub-record-check:6] Set("Local/*805318@from-internal-00000099;2", "__YEAR=2015") in new stack
    -- Executing [s@sub-record-check:7] Set("Local/*805318@from-internal-00000099;2", "__TIMESTR=20150130-050224") in new stack
    -- Executing [s@sub-record-check:8] Set("Local/*805318@from-internal-00000099;2", "__FROMEXTEN=5318") in new stack
    -- Executing [s@sub-record-check:9] Set("Local/*805318@from-internal-00000099;2", "__MON_FMT=wav") in new stack
    -- Executing [s@sub-record-check:10] NoOp("Local/*805318@from-internal-00000099;2", "Recordings initialized") in new stack
    -- Executing [s@sub-record-check:11] ExecIf("Local/*805318@from-internal-00000099;2", "0?Set(ARG3=dontcare)") in new stack
    -- Executing [s@sub-record-check:12] Set("Local/*805318@from-internal-00000099;2", "REC_POLICY_MODE_SAVE=") in new stack
    -- Executing [s@sub-record-check:13] GotoIf("Local/*805318@from-internal-00000099;2", "5?checkaction") in new stack
    -- Goto (sub-record-check,s,16)
    -- Executing [s@sub-record-check:16] GotoIf("Local/*805318@from-internal-00000099;2", "1?sub-record-check,exten,1") in new stack
    -- Goto (sub-record-check,exten,1)
    -- Executing [exten@sub-record-check:1] NoOp("Local/*805318@from-internal-00000099;2", "Exten Recording Check between 5318 and 5318") in new stack
    -- Executing [exten@sub-record-check:2] Set("Local/*805318@from-internal-00000099;2", "CALLTYPE=internal") in new stack
    -- Executing [exten@sub-record-check:3] ExecIf("Local/*805318@from-internal-00000099;2", "0?Set(CALLTYPE=)") in new stack
    -- Executing [exten@sub-record-check:4] Set("Local/*805318@from-internal-00000099;2", "CALLEE=dontcare") in new stack
    -- Executing [exten@sub-record-check:5] ExecIf("Local/*805318@from-internal-00000099;2", "0?Set(CALLEE=dontcare)") in new stack
    -- Executing [exten@sub-record-check:6] GotoIf("Local/*805318@from-internal-00000099;2", "0?callee") in new stack
    -- Executing [exten@sub-record-check:7] GotoIf("Local/*805318@from-internal-00000099;2", "1?caller") in new stack
    -- Goto (sub-record-check,exten,13)
    -- Executing [exten@sub-record-check:13] Set("Local/*805318@from-internal-00000099;2", "RECMODE=dontcare") in new stack
    -- Executing [exten@sub-record-check:14] ExecIf("Local/*805318@from-internal-00000099;2", "0?Set(RECMODE=dontcare)") in new stack
    -- Executing [exten@sub-record-check:15] ExecIf("Local/*805318@from-internal-00000099;2", "1?Set(RECMODE=dontcare)") in new stack
    -- Executing [exten@sub-record-check:16] Gosub("Local/*805318@from-internal-00000099;2", "recordcheck,1(dontcare,internal,5318)") in new stack
    -- Executing [recordcheck@sub-record-check:1] NoOp("Local/*805318@from-internal-00000099;2", "Starting recording check against dontcare") in new stack
    -- Executing [recordcheck@sub-record-check:2] Goto("Local/*805318@from-internal-00000099;2", "dontcare") in new stack
    -- Goto (sub-record-check,recordcheck,3)
    -- Executing [recordcheck@sub-record-check:3] Return("Local/*805318@from-internal-00000099;2", "") in new stack
    -- Executing [exten@sub-record-check:17] Return("Local/*805318@from-internal-00000099;2", "") in new stack
    -- Executing [*805318@ext-intercom:5] GotoIf("Local/*805318@from-internal-00000099;2", "0?end") in new stack
    -- Executing [*805318@ext-intercom:6] GotoIf("Local/*805318@from-internal-00000099;2", "0?end") in new stack
    -- Executing [*805318@ext-intercom:7] GotoIf("Local/*805318@from-internal-00000099;2", "0?allow") in new stack
    -- Executing [*805318@ext-intercom:8] GotoIf("Local/*805318@from-internal-00000099;2", "0?nointercom") in new stack
    -- Executing [*805318@ext-intercom:9] GotoIf("Local/*805318@from-internal-00000099;2", "0?nointercom") in new stack
    -- Executing [*805318@ext-intercom:10] Set("Local/*805318@from-internal-00000099;2", "DEVICES=5318") in new stack
    -- Executing [*805318@ext-intercom:11] GotoIf("Local/*805318@from-internal-00000099;2", "0?end") in new stack
    -- Executing [*805318@ext-intercom:12] Set("Local/*805318@from-internal-00000099;2", "OVERRIDE=reject") in new stack
    -- Executing [*805318@ext-intercom:13] Set("Local/*805318@from-internal-00000099;2", "LOOPCNT=1") in new stack
    -- Executing [*805318@ext-intercom:14] Set("Local/*805318@from-internal-00000099;2", "_SIPURI=") in new stack
    -- Executing [*805318@ext-intercom:15] Set("Local/*805318@from-internal-00000099;2", "_ALERTINFO=Ring Answer") in new stack
    -- Executing [*805318@ext-intercom:16] Set("Local/*805318@from-internal-00000099;2", "_CALLINFO=<uri>;answer-after=0") in new stack
    -- Executing [*805318@ext-intercom:17] Set("Local/*805318@from-internal-00000099;2", "_SIPURI=intercom=true") in new stack
    -- Executing [*805318@ext-intercom:18] Set("Local/*805318@from-internal-00000099;2", "_DTIME=5") in new stack
    -- Executing [*805318@ext-intercom:19] Set("Local/*805318@from-internal-00000099;2", "_ANSWERMACRO=") in new stack
    -- Executing [*805318@ext-intercom:20] GotoIf("Local/*805318@from-internal-00000099;2", "0?pagemode") in new stack
    -- Executing [*805318@ext-intercom:21] Macro("Local/*805318@from-internal-00000099;2", "autoanswer,5318") in new stack
    -- Executing [s@macro-autoanswer:1] GotoIf("Local/*805318@from-internal-00000099;2", "0?knowndial") in new stack
    -- Executing [s@macro-autoanswer:2] Set("Local/*805318@from-internal-00000099;2", "DEVICE=IAX2/5318") in new stack
    -- Executing [s@macro-autoanswer:3] GotoIf("Local/*805318@from-internal-00000099;2", "0?dopjsip") in new stack
    -- Executing [s@macro-autoanswer:4] Set("Local/*805318@from-internal-00000099;2", "KNOWNDIAL=IAX2/5318") in new stack
    -- Executing [s@macro-autoanswer:5] Goto("Local/*805318@from-internal-00000099;2", "knowndial") in new stack
    -- Goto (macro-autoanswer,s,19)
    -- Executing [s@macro-autoanswer:19] Set("Local/*805318@from-internal-00000099;2", "DIAL=IAX2/5318") in new stack
    -- Executing [s@macro-autoanswer:20] ExecIf("Local/*805318@from-internal-00000099;2", "0?Set(DIAL=DAHDI2/5318)") in new stack
    -- Executing [s@macro-autoanswer:21] GotoIf("Local/*805318@from-internal-00000099;2", "0?macro") in new stack
    -- Executing [s@macro-autoanswer:22] Set("Local/*805318@from-internal-00000099;2", "USERAGENT=") in new stack
    -- Executing [s@macro-autoanswer:23] ExecIf("Local/*805318@from-internal-00000099;2", "0?Set(USERAGENT=)") in new stack
    -- Executing [s@macro-autoanswer:24] ExecIf("Local/*805318@from-internal-00000099;2", "0?Set(CALLINFO=<sip:broadworks.net>;answer-after=0)") in new stack
    -- Executing [s@macro-autoanswer:25] ExecIf("Local/*805318@from-internal-00000099;2", "0?Set(ALERTINFO=Intercom)") in new stack
    -- Executing [s@macro-autoanswer:26] ExecIf("Local/*805318@from-internal-00000099;2", "0?Set(ALERTINFO=info=Auto Answer)") in new stack
    -- Executing [s@macro-autoanswer:27] ExecIf("Local/*805318@from-internal-00000099;2", "0?Set(ALERTINFO=ring-answer)") in new stack
    -- Executing [s@macro-autoanswer:28] ExecIf("Local/*805318@from-internal-00000099;2", "1?Set(__SIP_URI_OPTIONS=intercom=true)") in new stack
    -- Executing [*805318@ext-intercom:22] Set("Local/*805318@from-internal-00000099;2", "_DOPTIONS=A(beep)b(autoanswer^s^1(Ring Answer,<uri>;answer-after=0))") in new stack
    -- Executing [*805318@ext-intercom:23] ChanIsAvail("Local/*805318@from-internal-00000099;2", "IAX2/5318,s") in new stack
    -- Hungup 'IAX2/5318-1233'
    -- Executing [*805318@ext-intercom:24] ExecIf("Local/*805318@from-internal-00000099;2", "0?Set(AVAILORIGCHAN=IAX2/5318)") in new stack
    -- Executing [*805318@ext-intercom:25] GotoIf("Local/*805318@from-internal-00000099;2", "1?continue") in new stack
    -- Goto (ext-intercom,*805318,30)
    -- Executing [*805318@ext-intercom:30] NoOp("Local/*805318@from-internal-00000099;2", "Continuing with page") in new stack
    -- Executing [*805318@ext-intercom:31] GotoIf("Local/*805318@from-internal-00000099;2", "0?godial") in new stack
    -- Executing [*805318@ext-intercom:32] Set("Local/*805318@from-internal-00000099;2", "CONNECTEDLINE(name,i)=Johann Mobile") in new stack
    -- Executing [*805318@ext-intercom:33] Set("Local/*805318@from-internal-00000099;2", "CONNECTEDLINE(num)=5318") in new stack
    -- Executing [*805318@ext-intercom:34] Dial("Local/*805318@from-internal-00000099;2", "IAX2/5318,5,IA(beep)b(autoanswer^s^1(Ring Answer,<uri>;answer-after=0))") in new stack
    -- IAX2/5318-9756 Internal Gosub(autoanswer,s,1(Ring Answer,<uri>;answer-after=0)) start
    -- Executing [s@autoanswer:1] GosubIf("IAX2/5318-9756", "1?addheader,1(Alert-Info,Ring Answer)") in new stack
    -- Executing [addheader@autoanswer:1] SIPAddHeader("IAX2/5318-9756", "Alert-Info: Ring Answer") in new stack
    -- Executing [addheader@autoanswer:2] Set("IAX2/5318-9756", "PJSIP_HEADER(add,Alert-Info)=Ring Answer") in new stack
[2015-01-30 05:02:24] ERROR[5803][C-000003bf]: pbx.c:4387 ast_func_write: Function PJSIP_HEADER not registered
    -- Executing [addheader@autoanswer:3] Return("IAX2/5318-9756", "") in new stack
    -- Executing [s@autoanswer:2] GosubIf("IAX2/5318-9756", "1?addheader,1(Call-Info,<uri>;answer-after=0)") in new stack
    -- Executing [addheader@autoanswer:1] SIPAddHeader("IAX2/5318-9756", "Call-Info: <uri>;answer-after=0") in new stack
    -- Executing [addheader@autoanswer:2] Set("IAX2/5318-9756", "PJSIP_HEADER(add,Call-Info)=<uri>;answer-after=0") in new stack
[2015-01-30 05:02:24] ERROR[5803][C-000003bf]: pbx.c:4387 ast_func_write: Function PJSIP_HEADER not registered
    -- Executing [addheader@autoanswer:3] Return("IAX2/5318-9756", "") in new stack
    -- Executing [s@autoanswer:3] Return("IAX2/5318-9756", "") in new stack
  == Spawn extension (, *805318, 1) exited non-zero on 'IAX2/5318-9756'
    -- IAX2/5318-9756 Internal Gosub(autoanswer,s,1(Ring Answer,<uri>;answer-after=0)) complete GOSUB_RETVAL=
    -- Called IAX2/5318
    -- Call accepted by 164.80.170.150 (format speex)
    -- Format for call is (speex)
    -- IAX2/5318-9756 is ringing
    -- Nobody picked up in 5000 ms
    -- Hungup 'IAX2/5318-9756'
    -- Executing [*805318@ext-intercom:35] ExecIf("Local/*805318@from-internal-00000099;2", "?Return()") in new stack
    -- Executing [*805318@ext-intercom:36] Busy("Local/*805318@from-internal-00000099;2", "20") in new stack
       > Channel Local/*805318@from-internal-00000099;1 was never answered.
  == Spawn extension (ext-intercom, *805318, 36) exited non-zero on 'Local/*805318@from-internal-00000099;2'