Freepbx 14 Connected Line update prevented and immediate hang up

mlaihk · January 11, 2018, 6:39am

I am using and OBI202 acting as an FXO for my Freepbx 14 (asterisk 14). Everything is connected via PJSIP, together with 3 SIP trunks to an ITSP.
Everything works except for the OBI202 FXO with incoming calls. It will ring and if not picked up by an extension within the first ring, incoming call will get disconnected.

In the asterisk Log, I am seeing errors like this:
app_dial.c: Connected line update to PJSIP/obi28907870-000000d3 prevented.

before it immediately goes to the hangup script with code 127.

Out going calls using the OBI202 FXO is fine, so is incoming and outgoing on the other SIP trunks.

The OBI202 FXO and the Freepbx14 box is on the same network and has the same RTP ports configured so not likely a NAT problem.

Any help or insight will be much appreciated!

Marshall

tm1000 · January 11, 2018, 7:25am

That is more of a warning than anything, it just means that your SIP trunk doesnt support connected line updates (it doesn’t need to). We need to see your full log.

mlaihk · January 11, 2018, 9:46am

[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [28907870@from-pstn:1] Set("PJSIP/obi28907870-00000000", "__DIRECTION=INBOUND") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [28907870@from-pstn:2] Gosub("PJSIP/obi28907870-00000000", "sub-record-check,s,1(in,28907870,dontcare)") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@sub-record-check:1] GotoIf("PJSIP/obi28907870-00000000", "0?initialized") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@sub-record-check:2] Set("PJSIP/obi28907870-00000000", "__REC_STATUS=INITIALIZED") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@sub-record-check:3] Set("PJSIP/obi28907870-00000000", "NOW=1515663686") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@sub-record-check:4] Set("PJSIP/obi28907870-00000000", "__DAY=11") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@sub-record-check:5] Set("PJSIP/obi28907870-00000000", "__MONTH=01") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@sub-record-check:6] Set("PJSIP/obi28907870-00000000", "__YEAR=2018") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@sub-record-check:7] Set("PJSIP/obi28907870-00000000", "__TIMESTR=20180111-174126") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@sub-record-check:8] Set("PJSIP/obi28907870-00000000", "__FROMEXTEN=unknown") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@sub-record-check:9] Set("PJSIP/obi28907870-00000000", "__MON_FMT=wav") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@sub-record-check:10] NoOp("PJSIP/obi28907870-00000000", "Recordings initialized") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@sub-record-check:11] ExecIf("PJSIP/obi28907870-00000000", "0?Set(ARG3=dontcare)") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@sub-record-check:12] Set("PJSIP/obi28907870-00000000", "REC_POLICY_MODE_SAVE=") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@sub-record-check:13] ExecIf("PJSIP/obi28907870-00000000", "0?Set(REC_STATUS=NO)") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@sub-record-check:14] GotoIf("PJSIP/obi28907870-00000000", "2?checkaction") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx_builtins.c: Goto (sub-record-check,s,17)
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@sub-record-check:17] GotoIf("PJSIP/obi28907870-00000000", "1?sub-record-check,in,1") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx_builtins.c: Goto (sub-record-check,in,1)
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [in@sub-record-check:1] NoOp("PJSIP/obi28907870-00000000", "Inbound Recording Check to 28907870") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [in@sub-record-check:2] Set("PJSIP/obi28907870-00000000", "FROMEXTEN=unknown") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [in@sub-record-check:3] ExecIf("PJSIP/obi28907870-00000000", "8?Set(FROMEXTEN=97631267)") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [in@sub-record-check:4] Gosub("PJSIP/obi28907870-00000000", "recordcheck,1(dontcare,in,28907870)") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("PJSIP/obi28907870-00000000", "Starting recording check against dontcare") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("PJSIP/obi28907870-00000000", "dontcare") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [recordcheck@sub-record-check:3] Return("PJSIP/obi28907870-00000000", "") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [in@sub-record-check:5] Return("PJSIP/obi28907870-00000000", "") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [28907870@from-pstn:3] Gosub("PJSIP/obi28907870-00000000", "app-blacklist-check,s,1()") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@app-blacklist-check:1] GotoIf("PJSIP/obi28907870-00000000", "0?blacklisted") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@app-blacklist-check:2] Set("PJSIP/obi28907870-00000000", "CALLED_BLACKLIST=1") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@app-blacklist-check:3] Return("PJSIP/obi28907870-00000000", "") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [28907870@from-pstn:4] Set("PJSIP/obi28907870-00000000", "__FROM_DID=28907870") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [28907870@from-pstn:5] Set("PJSIP/obi28907870-00000000", "CDR(did)=28907870") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [28907870@from-pstn:6] ExecIf("PJSIP/obi28907870-00000000", "1 ?Set(CALLERID(name)=97631267)") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [28907870@from-pstn:7] Set("PJSIP/obi28907870-00000000", "__MOHCLASS=") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [28907870@from-pstn:8] Set("PJSIP/obi28907870-00000000", "__REVERSAL_REJECT=FALSE") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [28907870@from-pstn:9] GotoIf("PJSIP/obi28907870-00000000", "1?post-reverse-charge") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx_builtins.c: Goto (from-pstn,28907870,11)
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [28907870@from-pstn:11] NoOp("PJSIP/obi28907870-00000000", "") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [28907870@from-pstn:12] Set("PJSIP/obi28907870-00000000", "__CALLINGNAMEPRES_SV=allowed_not_screened") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [28907870@from-pstn:13] Set("PJSIP/obi28907870-00000000", "__CALLINGNUMPRES_SV=allowed_not_screened") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [28907870@from-pstn:14] Set("PJSIP/obi28907870-00000000", "CALLERID(name-pres)=allowed_not_screened") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [28907870@from-pstn:15] Set("PJSIP/obi28907870-00000000", "CALLERID(num-pres)=allowed_not_screened") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [28907870@from-pstn:16] NoOp("PJSIP/obi28907870-00000000", "CallerID Entry Point") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [28907870@from-pstn:17] Macro("PJSIP/obi28907870-00000000", "prepend-cid,T1:") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-prepend-cid:1] GotoIf("PJSIP/obi28907870-00000000", "1?REPCID") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx_builtins.c: Goto (macro-prepend-cid,s,5)
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-prepend-cid:5] Set("PJSIP/obi28907870-00000000", "_RGPREFIX=T1:") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-prepend-cid:6] Set("PJSIP/obi28907870-00000000", "CALLERID(name)=T1:97631267") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [28907870@from-pstn:18] Set("PJSIP/obi28907870-00000000", "__CRM_DIRECTION=INBOUND") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [28907870@from-pstn:19] Set("PJSIP/obi28907870-00000000", "__CRM_SOURCE=97631267") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [28907870@from-pstn:20] Set("PJSIP/obi28907870-00000000", "__CRM_LINKEDID=1515663686.0") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [28907870@from-pstn:21] ExecIf("PJSIP/obi28907870-00000000", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [28907870@from-pstn:22] Goto("PJSIP/obi28907870-00000000", "from-did-direct,203,1") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx_builtins.c: Goto (from-did-direct,203,1)
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [203@from-did-direct:1] GotoIf("PJSIP/obi28907870-00000000", "1?ext-local,203,1:followme-check,203,1") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx_builtins.c: Goto (ext-local,203,1)
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [203@ext-local:1] Set("PJSIP/obi28907870-00000000", "__RINGTIMER=15") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [203@ext-local:2] Macro("PJSIP/obi28907870-00000000", "exten-vm,novm,203,0,0,0") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-exten-vm:1] Macro("PJSIP/obi28907870-00000000", "user-callerid,") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-user-callerid:1] Set("PJSIP/obi28907870-00000000", "TOUCH_MONITOR=1515663686.0") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-user-callerid:2] Set("PJSIP/obi28907870-00000000", "AMPUSER=97631267") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-user-callerid:3] GotoIf("PJSIP/obi28907870-00000000", "0?report") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-user-callerid:4] ExecIf("PJSIP/obi28907870-00000000", "1?Set(REALCALLERIDNUM=97631267)") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-user-callerid:5] Set("PJSIP/obi28907870-00000000", "AMPUSER=") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-user-callerid:6] GotoIf("PJSIP/obi28907870-00000000", "0?limit") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-user-callerid:7] Set("PJSIP/obi28907870-00000000", "AMPUSERCIDNAME=") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-user-callerid:8] ExecIf("PJSIP/obi28907870-00000000", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-user-callerid:9] GotoIf("PJSIP/obi28907870-00000000", "1?report") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx_builtins.c: Goto (macro-user-callerid,s,16)
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-user-callerid:16] NoOp("PJSIP/obi28907870-00000000", "Macro Depth is 2") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-user-callerid:17] GotoIf("PJSIP/obi28907870-00000000", "1?report2:macroerror") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx_builtins.c: Goto (macro-user-callerid,s,19)
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-user-callerid:19] GotoIf("PJSIP/obi28907870-00000000", "0?continue") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-user-callerid:20] Set("PJSIP/obi28907870-00000000", "__TTL=64") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-user-callerid:21] GotoIf("PJSIP/obi28907870-00000000", "1?continue") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx_builtins.c: Goto (macro-user-callerid,s,37)
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-user-callerid:37] Set("PJSIP/obi28907870-00000000", "CALLERID(number)=97631267") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-user-callerid:38] Set("PJSIP/obi28907870-00000000", "CALLERID(name)=T1:97631267") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-user-callerid:39] GotoIf("PJSIP/obi28907870-00000000", "0?cnum") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-user-callerid:40] Set("PJSIP/obi28907870-00000000", "CDR(cnam)=T1:97631267") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-user-callerid:41] Set("PJSIP/obi28907870-00000000", "CDR(cnum)=97631267") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-user-callerid:42] Set("PJSIP/obi28907870-00000000", "CHANNEL(language)=en") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-exten-vm:2] Set("PJSIP/obi28907870-00000000", "RingGroupMethod=none") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-exten-vm:3] Set("PJSIP/obi28907870-00000000", "__EXTTOCALL=203") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-exten-vm:4] Set("PJSIP/obi28907870-00000000", "__PICKUPMARK=203") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-exten-vm:5] Set("PJSIP/obi28907870-00000000", "RT=") in new stack
[2018-01-11 17:41:26] ERROR[3693][C-00000001] pbx_functions.c: Function SIP_HEADER not registered
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-exten-vm:6] ExecIf("PJSIP/obi28907870-00000000", "0?Macro(vm,novm,DIRECTDIAL,)") in new stack
[2018-01-11 17:41:26] ERROR[3693][C-00000001] pbx_functions.c: Function SIP_HEADER not registered
[2018-01-11 17:41:26] ERROR[3693][C-00000001] pbx_functions.c: Function SIP_HEADER not registered
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-exten-vm:7] ExecIf("PJSIP/obi28907870-00000000", "0?MacroExit()") in new stack
[2018-01-11 17:41:26] ERROR[3693][C-00000001] pbx_functions.c: Function SIP_HEADER not registered
[2018-01-11 17:41:26] ERROR[3693][C-00000001] pbx_functions.c: Function SIP_HEADER not registered
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-exten-vm:8] ExecIf("PJSIP/obi28907870-00000000", "0?Gosub(ext-intercom,*80203,1())") in new stack
[2018-01-11 17:41:26] ERROR[3693][C-00000001] pbx_functions.c: Function SIP_HEADER not registered
[2018-01-11 17:41:26] ERROR[3693][C-00000001] pbx_functions.c: Function SIP_HEADER not registered
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-exten-vm:9] ExecIf("PJSIP/obi28907870-00000000", "0?MacroExit()") in new stack
[2018-01-11 17:41:26] ERROR[3693][C-00000001] pbx_functions.c: Function SIP_HEADER not registered
[2018-01-11 17:41:26] ERROR[3693][C-00000001] pbx_functions.c: Function SIP_HEADER not registered
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-exten-vm:10] ExecIf("PJSIP/obi28907870-00000000", "0?ChanSpy(PJSIP/203,q)") in new stack
[2018-01-11 17:41:26] ERROR[3693][C-00000001] pbx_functions.c: Function SIP_HEADER not registered
[2018-01-11 17:41:26] ERROR[3693][C-00000001] pbx_functions.c: Function SIP_HEADER not registered
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-exten-vm:11] ExecIf("PJSIP/obi28907870-00000000", "0?MacroExit()") in new stack
[2018-01-11 17:41:26] ERROR[3693][C-00000001] pbx_functions.c: Function SIP_HEADER not registered
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-exten-vm:12] Gosub("PJSIP/obi28907870-00000000", "sub-record-check,s,1(exten,203,dontcare)") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@sub-record-check:1] GotoIf("PJSIP/obi28907870-00000000", "8?initialized") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx_builtins.c: Goto (sub-record-check,s,10)
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@sub-record-check:10] NoOp("PJSIP/obi28907870-00000000", "Recordings initialized") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@sub-record-check:11] ExecIf("PJSIP/obi28907870-00000000", "0?Set(ARG3=dontcare)") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@sub-record-check:12] Set("PJSIP/obi28907870-00000000", "REC_POLICY_MODE_SAVE=") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@sub-record-check:13] ExecIf("PJSIP/obi28907870-00000000", "0?Set(REC_STATUS=NO)") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@sub-record-check:14] GotoIf("PJSIP/obi28907870-00000000", "5?checkaction") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx_builtins.c: Goto (sub-record-check,s,17)
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@sub-record-check:17] GotoIf("PJSIP/obi28907870-00000000", "1?sub-record-check,exten,1") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx_builtins.c: Goto (sub-record-check,exten,1)
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [exten@sub-record-check:1] NoOp("PJSIP/obi28907870-00000000", "Exten Recording Check between 97631267 and 203") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [exten@sub-record-check:2] Set("PJSIP/obi28907870-00000000", "CALLTYPE=external") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [exten@sub-record-check:3] ExecIf("PJSIP/obi28907870-00000000", "0?Set(CALLTYPE=)") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [exten@sub-record-check:4] Set("PJSIP/obi28907870-00000000", "CALLEE=dontcare") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [exten@sub-record-check:5] ExecIf("PJSIP/obi28907870-00000000", "0?Set(CALLEE=dontcare)") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [exten@sub-record-check:6] GotoIf("PJSIP/obi28907870-00000000", "1?callee") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx_builtins.c: Goto (sub-record-check,exten,11)
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [exten@sub-record-check:11] Gosub("PJSIP/obi28907870-00000000", "recordcheck,1(dontcare,external,203)") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("PJSIP/obi28907870-00000000", "Starting recording check against dontcare") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("PJSIP/obi28907870-00000000", "dontcare") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [recordcheck@sub-record-check:3] Return("PJSIP/obi28907870-00000000", "") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [exten@sub-record-check:12] Return("PJSIP/obi28907870-00000000", "") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-exten-vm:13] GotoIf("PJSIP/obi28907870-00000000", "1?macrodial") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx_builtins.c: Goto (macro-exten-vm,s,19)
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-exten-vm:19] GosubIf("PJSIP/obi28907870-00000000", "0?clrheader,1()") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-exten-vm:20] Macro("PJSIP/obi28907870-00000000", "dial-one,,Ttr,203") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-dial-one:1] Set("PJSIP/obi28907870-00000000", "DEXTEN=203") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-dial-one:2] Set("PJSIP/obi28907870-00000000", "__CRM_SOURCE=97631267") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-dial-one:3] ExecIf("PJSIP/obi28907870-00000000", "0?Set(EXTTOCALL=203)") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-dial-one:4] Set("PJSIP/obi28907870-00000000", "DIALSTATUS_CW=") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-dial-one:5] GosubIf("PJSIP/obi28907870-00000000", "0?screen,1()") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-dial-one:6] GosubIf("PJSIP/obi28907870-00000000", "0?cf,1()") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-dial-one:7] GotoIf("PJSIP/obi28907870-00000000", "1?skip1") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx_builtins.c: Goto (macro-dial-one,s,10)
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-dial-one:10] GotoIf("PJSIP/obi28907870-00000000", "0?nodial") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-dial-one:11] GotoIf("PJSIP/obi28907870-00000000", "0?continue") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-dial-one:12] Set("PJSIP/obi28907870-00000000", "EXTHASCW=") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-dial-one:13] GotoIf("PJSIP/obi28907870-00000000", "1?next1:cwinusebusy") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx_builtins.c: Goto (macro-dial-one,s,14)
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-dial-one:14] GotoIf("PJSIP/obi28907870-00000000", "0?docfu:skip3") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx_builtins.c: Goto (macro-dial-one,s,18)
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-dial-one:18] GotoIf("PJSIP/obi28907870-00000000", "1?next2:continue") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx_builtins.c: Goto (macro-dial-one,s,19)
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-dial-one:19] GotoIf("PJSIP/obi28907870-00000000", "1?continue") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx_builtins.c: Goto (macro-dial-one,s,27)
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-dial-one:27] GotoIf("PJSIP/obi28907870-00000000", "0?nodial") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-dial-one:28] GosubIf("PJSIP/obi28907870-00000000", "1?dstring,1():dlocal,1()") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [dstring@macro-dial-one:1] Set("PJSIP/obi28907870-00000000", "DSTRING=") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [dstring@macro-dial-one:2] Set("PJSIP/obi28907870-00000000", "DEVICES=203") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [dstring@macro-dial-one:3] ExecIf("PJSIP/obi28907870-00000000", "0?Return()") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [dstring@macro-dial-one:4] ExecIf("PJSIP/obi28907870-00000000", "0?Set(DEVICES=03)") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [dstring@macro-dial-one:5] Set("PJSIP/obi28907870-00000000", "LOOPCNT=1") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [dstring@macro-dial-one:6] Set("PJSIP/obi28907870-00000000", "ITER=1") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [dstring@macro-dial-one:7] Set("PJSIP/obi28907870-00000000", "THISDIAL=PJSIP/203") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [dstring@macro-dial-one:8] GosubIf("PJSIP/obi28907870-00000000", "1?zap2dahdi,1()") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [zap2dahdi@macro-dial-one:1] ExecIf("PJSIP/obi28907870-00000000", "0?Return()") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [zap2dahdi@macro-dial-one:2] Set("PJSIP/obi28907870-00000000", "NEWDIAL=") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [zap2dahdi@macro-dial-one:3] Set("PJSIP/obi28907870-00000000", "LOOPCNT2=1") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [zap2dahdi@macro-dial-one:4] Set("PJSIP/obi28907870-00000000", "ITER2=1") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [zap2dahdi@macro-dial-one:5] Set("PJSIP/obi28907870-00000000", "THISPART2=PJSIP/203") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [zap2dahdi@macro-dial-one:6] ExecIf("PJSIP/obi28907870-00000000", "0?Set(THISPART2=DAHDIIP/203)") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [zap2dahdi@macro-dial-one:7] Set("PJSIP/obi28907870-00000000", "NEWDIAL=PJSIP/203&") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [zap2dahdi@macro-dial-one:8] Set("PJSIP/obi28907870-00000000", "ITER2=2") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [zap2dahdi@macro-dial-one:9] GotoIf("PJSIP/obi28907870-00000000", "0?begin2") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [zap2dahdi@macro-dial-one:10] Set("PJSIP/obi28907870-00000000", "THISDIAL=PJSIP/203") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [zap2dahdi@macro-dial-one:11] Return("PJSIP/obi28907870-00000000", "") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [dstring@macro-dial-one:9] GotoIf("PJSIP/obi28907870-00000000", "0?docheck") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [dstring@macro-dial-one:10] NoOp("PJSIP/obi28907870-00000000", "Debug: Found PJSIP Destination PJSIP/203") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [dstring@macro-dial-one:11] GotoIf("PJSIP/obi28907870-00000000", "0?doset") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [dstring@macro-dial-one:12] NoOp("PJSIP/obi28907870-00000000", "Debug: Updating PJSIP Destination with PJSIP_DIAL_CONTACTS") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [dstring@macro-dial-one:13] Set("PJSIP/obi28907870-00000000", "THISDIAL=PJSIP/203/sip:[email protected]:5060") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [dstring@macro-dial-one:14] GotoIf("PJSIP/obi28907870-00000000", "0?skipset") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [dstring@macro-dial-one:15] Set("PJSIP/obi28907870-00000000", "DSTRING=PJSIP/203/sip:[email protected]:5060&") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [dstring@macro-dial-one:16] Set("PJSIP/obi28907870-00000000", "ITER=2") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [dstring@macro-dial-one:17] GotoIf("PJSIP/obi28907870-00000000", "0?begin") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [dstring@macro-dial-one:18] ExecIf("PJSIP/obi28907870-00000000", "0?Return()") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [dstring@macro-dial-one:19] Set("PJSIP/obi28907870-00000000", "DSTRING=PJSIP/203/sip:[email protected]:5060") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [dstring@macro-dial-one:20] Return("PJSIP/obi28907870-00000000", "") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-dial-one:29] GotoIf("PJSIP/obi28907870-00000000", "0?nodial") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-dial-one:30] GotoIf("PJSIP/obi28907870-00000000", "0?skiptrace") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-dial-one:31] GosubIf("PJSIP/obi28907870-00000000", "1?ctset,1():ctclear,1()") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [ctset@macro-dial-one:1] Set("PJSIP/obi28907870-00000000", "DB(CALLTRACE/203)=97631267") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [ctset@macro-dial-one:2] Return("PJSIP/obi28907870-00000000", "") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-dial-one:32] Set("PJSIP/obi28907870-00000000", "D_OPTIONS=Ttr") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-dial-one:33] NoOp("PJSIP/obi28907870-00000000", "Blind Transfer: , Attended Transfer: , User: , Alert Info: ") in new stack

mlaihk · January 11, 2018, 9:46am

[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-dial-one:34] ExecIf("PJSIP/obi28907870-00000000", "0?Set(ALERT_INFO=)") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-dial-one:35] ExecIf("PJSIP/obi28907870-00000000", "0?Set(ALERT_INFO=)") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-dial-one:36] ExecIf("PJSIP/obi28907870-00000000", "0?Set(ALERT_INFO=)") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-dial-one:37] ExecIf("PJSIP/obi28907870-00000000", "0?Set(ALERT_INFO=Normal;volume=)") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-dial-one:38] ExecIf("PJSIP/obi28907870-00000000", "0?Set(ALERT_INFO=Normal;volume=)") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-dial-one:39] GosubIf("PJSIP/obi28907870-00000000", "0?func-set-sipheader,s,1(Alert-Info,)") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-dial-one:40] ExecIf("PJSIP/obi28907870-00000000", "0?Set(CHANNEL(musicclass)=)") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-dial-one:41] GosubIf("PJSIP/obi28907870-00000000", "0?qwait,1()") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-dial-one:42] Set("PJSIP/obi28907870-00000000", "__CWIGNORE=") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-dial-one:43] Set("PJSIP/obi28907870-00000000", "__KEEPCID=TRUE") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-dial-one:44] GotoIf("PJSIP/obi28907870-00000000", "0?usegoto,1") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-dial-one:45] GotoIf("PJSIP/obi28907870-00000000", "1?godial") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx_builtins.c: Goto (macro-dial-one,s,50)
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-dial-one:50] Macro("PJSIP/obi28907870-00000000", "dialout-one-predial-hook,") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-dialout-one-predial-hook:1] MacroExit("PJSIP/obi28907870-00000000", "") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-dial-one:51] ExecIf("PJSIP/obi28907870-00000000", "1?Set(D_OPTIONS=trI)") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-dial-one:52] NoOp("PJSIP/obi28907870-00000000", "") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-dial-one:53] Dial("PJSIP/obi28907870-00000000", "PJSIP/203/sip:[email protected]:5060,,trIb(func-apply-sipheaders^s^1)") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] app_stack.c: PJSIP/203-00000001 Internal Gosub(func-apply-sipheaders,s,1) start
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf("PJSIP/203-00000001", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp("PJSIP/203-00000001", "Applying SIP Headers to channel") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@func-apply-sipheaders:3] Set("PJSIP/203-00000001", "SIPHEADERKEYS=") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@func-apply-sipheaders:4] While("PJSIP/203-00000001", "0") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] app_while.c: Jumping to priority 7
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] pbx.c: Executing [s@func-apply-sipheaders:8] Return("PJSIP/203-00000001", "") in new stack
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] app_stack.c: Spawn extension (from-internal, 203, 1) exited non-zero on 'PJSIP/203-00000001'
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] app_stack.c: PJSIP/203-00000001 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] app_dial.c: Called PJSIP/203/sip:[email protected]:5060
[2018-01-11 17:41:26] VERBOSE[3693][C-00000001] app_dial.c: Connected line update to PJSIP/obi28907870-00000000 prevented.
[2018-01-11 17:41:27] VERBOSE[3693][C-00000001] app_dial.c: PJSIP/203-00000001 is ringing
[2018-01-11 17:41:37] VERBOSE[3693][C-00000001] app_stack.c: PJSIP/203-00000001 Internal Gosub(crm-hangup,s,1) start
[2018-01-11 17:41:37] VERBOSE[3693][C-00000001] pbx.c: Executing [s@crm-hangup:1] NoOp("PJSIP/203-00000001", "Sending Hangup to CRM") in new stack
[2018-01-11 17:41:37] VERBOSE[3693][C-00000001] pbx.c: Executing [s@crm-hangup:2] NoOp("PJSIP/203-00000001", "HANGUP CAUSE: 0") in new stack
[2018-01-11 17:41:37] VERBOSE[3693][C-00000001] pbx.c: Executing [s@crm-hangup:3] ExecIf("PJSIP/203-00000001", "0?Set(__CRM_VOICEMAIL=)") in new stack
[2018-01-11 17:41:37] VERBOSE[3693][C-00000001] pbx.c: Executing [s@crm-hangup:4] NoOp("PJSIP/203-00000001", "MASTER CHANNEL: 1515663686.1 = 1515663686.0") in new stack
[2018-01-11 17:41:37] VERBOSE[3693][C-00000001] pbx.c: Executing [s@crm-hangup:5] GotoIf("PJSIP/203-00000001", "1?return") in new stack
[2018-01-11 17:41:37] VERBOSE[3693][C-00000001] pbx_builtins.c: Goto (crm-hangup,s,8)
[2018-01-11 17:41:37] VERBOSE[3693][C-00000001] pbx.c: Executing [s@crm-hangup:8] Return("PJSIP/203-00000001", "") in new stack
[2018-01-11 17:41:37] VERBOSE[3693][C-00000001] app_stack.c: Spawn extension (from-internal, 203, 1) exited non-zero on 'PJSIP/203-00000001'
[2018-01-11 17:41:37] VERBOSE[3693][C-00000001] app_stack.c: PJSIP/203-00000001 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
[2018-01-11 17:41:37] VERBOSE[3693][C-00000001] app_macro.c: Spawn extension (macro-dial-one, s, 53) exited non-zero on 'PJSIP/obi28907870-00000000' in macro 'dial-one'
[2018-01-11 17:41:37] VERBOSE[3693][C-00000001] app_macro.c: Spawn extension (macro-exten-vm, s, 20) exited non-zero on 'PJSIP/obi28907870-00000000' in macro 'exten-vm'
[2018-01-11 17:41:37] VERBOSE[3693][C-00000001] pbx.c: Spawn extension (ext-local, 203, 2) exited non-zero on 'PJSIP/obi28907870-00000000'
[2018-01-11 17:41:37] VERBOSE[3693][C-00000001] pbx.c: Executing [h@ext-local:1] Macro("PJSIP/obi28907870-00000000", "hangupcall,") in new stack
[2018-01-11 17:41:37] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("PJSIP/obi28907870-00000000", "1?theend") in new stack
[2018-01-11 17:41:37] VERBOSE[3693][C-00000001] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2018-01-11 17:41:37] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("PJSIP/obi28907870-00000000", "0?Set(CDR(recordingfile)=)") in new stack
[2018-01-11 17:41:37] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-hangupcall:4] NoOp("PJSIP/obi28907870-00000000", "PJSIP/203-00000001 monior file= ") in new stack
[2018-01-11 17:41:37] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-hangupcall:5] AGI("PJSIP/obi28907870-00000000", "attendedtransfer-rec-restart.php,PJSIP/203-00000001,") in new stack
[2018-01-11 17:41:37] VERBOSE[3693][C-00000001] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/attendedtransfer-rec-restart.php
[2018-01-11 17:41:37] VERBOSE[3693][C-00000001] res_agi.c: <PJSIP/obi28907870-00000000>AGI Script attendedtransfer-rec-restart.php completed, returning 0
[2018-01-11 17:41:37] VERBOSE[3693][C-00000001] pbx.c: Executing [s@macro-hangupcall:6] Hangup("PJSIP/obi28907870-00000000", "") in new stack
[2018-01-11 17:41:37] VERBOSE[3693][C-00000001] app_macro.c: Spawn extension (macro-hangupcall, s, 6) exited non-zero on 'PJSIP/obi28907870-00000000' in macro 'hangupcall'
[2018-01-11 17:41:37] VERBOSE[3693][C-00000001] pbx.c: Spawn extension (ext-local, h, 1) exited non-zero on 'PJSIP/obi28907870-00000000'
[2018-01-11 17:41:37] VERBOSE[3693][C-00000001] app_stack.c: PJSIP/obi28907870-00000000 Internal Gosub(crm-hangup,s,1) start
[2018-01-11 17:41:37] VERBOSE[3693][C-00000001] pbx.c: Executing [s@crm-hangup:1] NoOp("PJSIP/obi28907870-00000000", "Sending Hangup to CRM") in new stack
[2018-01-11 17:41:37] VERBOSE[3693][C-00000001] pbx.c: Executing [s@crm-hangup:2] NoOp("PJSIP/obi28907870-00000000", "HANGUP CAUSE: 127") in new stack
[2018-01-11 17:41:37] VERBOSE[3693][C-00000001] pbx.c: Executing [s@crm-hangup:3] ExecIf("PJSIP/obi28907870-00000000", "0?Set(__CRM_VOICEMAIL=)") in new stack
[2018-01-11 17:41:37] VERBOSE[3693][C-00000001] pbx.c: Executing [s@crm-hangup:4] NoOp("PJSIP/obi28907870-00000000", "MASTER CHANNEL: 1515663686.0 = 1515663686.0") in new stack
[2018-01-11 17:41:37] VERBOSE[3693][C-00000001] pbx.c: Executing [s@crm-hangup:5] GotoIf("PJSIP/obi28907870-00000000", "0?return") in new stack
[2018-01-11 17:41:37] VERBOSE[3693][C-00000001] pbx.c: Executing [s@crm-hangup:6] Set("PJSIP/obi28907870-00000000", "__CRM_HANGUP=1") in new stack
[2018-01-11 17:41:37] VERBOSE[3693][C-00000001] pbx.c: Executing [s@crm-hangup:7] AGI("PJSIP/obi28907870-00000000", "sangomacrm.agi") in new stack
[2018-01-11 17:41:37] VERBOSE[3693][C-00000001] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
[2018-01-11 17:41:38] VERBOSE[3693][C-00000001] res_agi.c: <PJSIP/obi28907870-00000000>AGI Script sangomacrm.agi completed, returning 0
[2018-01-11 17:41:38] VERBOSE[3693][C-00000001] pbx.c: Executing [s@crm-hangup:8] Return("PJSIP/obi28907870-00000000", "") in new stack
[2018-01-11 17:41:38] VERBOSE[3693][C-00000001] app_stack.c: Spawn extension (ext-local, h, 1) exited non-zero on 'PJSIP/obi28907870-00000000'
[2018-01-11 17:41:38] VERBOSE[3693][C-00000001] app_stack.c: PJSIP/obi28907870-00000000 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=

mlaihk · January 19, 2018, 3:15am

Nevermind guys. Turns out it was due to a screw up at my local telco where they forwarded my pstn line to a non-existent phone number after 5 rings…

system · January 20, 2018, 3:15am

This topic was automatically closed 24 hours after the last reply. New replies are no longer allowed.