Line auto hands up an incoming call

freepbx
Tags: #<Tag:0x00007fafcd3bbb70>

(Peter Ski) #1

I think this is a configuration issue. codec perhaps?

im trying to have google call so i can verify my number, its a robot call that gives me a 6 digit number I can then enter into google, problem is i cant pickup the call… it rings half a ring and then automatically hangs up!! I think it may be a codec issue, however I am not well enough versed to make that speculation.

here is the asterisk log after the hangup

[2019-11-15 15:50:25] VERBOSE[1196][C-00000009] netsock2.c: Using SIP RTP TOS bits 184
[2019-11-15 15:50:25] VERBOSE[1196][C-00000009] netsock2.c: Using SIP RTP CoS mark 5
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [2098457171@from-pstn:1] NoOp(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “Catch-All DID Match - Found 2098457171 - You probably want a DID for this.”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [2098457171@from-pstn:2] Set(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “__FROM_DID=2098457171”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [2098457171@from-pstn:3] Goto(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “ext-did,s,1”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx_builtins.c: Goto (ext-did,s,1)
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@ext-did:1] Set(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “__DIRECTION=INBOUND”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@ext-did:2] Set(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “CHANNEL(tonezone)=us”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@ext-did:3] ExecIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “0?Set(__FROM_DID=s)”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@ext-did:4] Set(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “CDR(did)=2098457171”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@ext-did:5] ExecIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “0 ?Set(CALLERID(name)=12024558888)”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@ext-did:6] Set(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “__MOHCLASS=”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@ext-did:7] Set(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “__REVERSAL_REJECT=FALSE”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@ext-did:8] GotoIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “1?post-reverse-charge”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx_builtins.c: Goto (ext-did,s,10)
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@ext-did:10] NoOp(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@ext-did:11] Set(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “__CALLINGNAMEPRES_SV=allowed_not_screened”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@ext-did:12] Set(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “__CALLINGNUMPRES_SV=allowed_not_screened”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@ext-did:13] Set(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “CALLERID(name-pres)=allowed_not_screened”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@ext-did:14] Set(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “CALLERID(num-pres)=allowed_not_screened”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@ext-did:15] NoOp(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “CallerID Entry Point”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@ext-did:16] Goto(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “from-did-direct,101,1”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx_builtins.c: Goto (from-did-direct,101,1)
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [101@from-did-direct:1] Set(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “__RINGTIMER=15”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [101@from-did-direct:2] Macro(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “exten-vm,101,101,0,0,0”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-exten-vm:1] Macro(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “user-callerid,”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-user-callerid:1] Set(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “TOUCH_MONITOR=1573833025.16”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-user-callerid:2] Set(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “AMPUSER=12024558888”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-user-callerid:3] GotoIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “0?report”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-user-callerid:4] ExecIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “1?Set(REALCALLERIDNUM=12024558888)”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-user-callerid:5] Set(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “AMPUSER=”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-user-callerid:6] GotoIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “0?limit”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-user-callerid:7] Set(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “AMPUSERCIDNAME=”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-user-callerid:8] ExecIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “0?Set(__CIDMASQUERADING=TRUE)”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-user-callerid:9] GotoIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “1?report”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx_builtins.c: Goto (macro-user-callerid,s,16)
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-user-callerid:16] NoOp(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “Macro Depth is 2”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-user-callerid:17] GotoIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “1?report2:macroerror”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx_builtins.c: Goto (macro-user-callerid,s,18)
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-user-callerid:18] GotoIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “0?continue”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-user-callerid:19] Set(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “__TTL=64”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-user-callerid:20] GotoIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “1?continue”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx_builtins.c: Goto (macro-user-callerid,s,36)
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-user-callerid:36] Set(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “CALLERID(number)=12024558888”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-user-callerid:37] Set(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “CALLERID(name)=WASHINGTON DC”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-user-callerid:38] GotoIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “0?cnum”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-user-callerid:39] Set(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “CDR(cnam)=WASHINGTON DC”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-user-callerid:40] Set(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “CDR(cnum)=12024558888”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-user-callerid:41] Set(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “CHANNEL(language)=en”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-exten-vm:2] Set(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “RingGroupMethod=none”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-exten-vm:3] Set(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “__EXTTOCALL=101”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-exten-vm:4] Set(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “__PICKUPMARK=101”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-exten-vm:5] Set(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “RT=15”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-exten-vm:6] Gosub(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “sub-record-check,s,1(exten,101,dontcare)”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@sub-record-check:1] GotoIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “0?initialized”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@sub-record-check:2] Set(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “__REC_STATUS=INITIALIZED”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@sub-record-check:3] Set(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “NOW=1573833025”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@sub-record-check:4] Set(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “__DAY=15”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@sub-record-check:5] Set(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “__MONTH=11”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@sub-record-check:6] Set(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “__YEAR=2019”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@sub-record-check:7] Set(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “__TIMESTR=20191115-155025”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@sub-record-check:8] Set(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “__FROMEXTEN=12024558888”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@sub-record-check:9] Set(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “__MON_FMT=wav”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@sub-record-check:10] NoOp(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “Recordings initialized”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@sub-record-check:11] ExecIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “0?Set(ARG3=dontcare)”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@sub-record-check:12] Set(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “REC_POLICY_MODE_SAVE=”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@sub-record-check:13] ExecIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “0?Set(REC_STATUS=NO)”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@sub-record-check:14] GotoIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “5?checkaction”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx_builtins.c: Goto (sub-record-check,s,17)
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@sub-record-check:17] GotoIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “1?sub-record-check,exten,1”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx_builtins.c: Goto (sub-record-check,exten,1)
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [exten@sub-record-check:1] NoOp(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “Exten Recording Check between 12024558888 and 101”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [exten@sub-record-check:2] Set(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “CALLTYPE=external”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [exten@sub-record-check:3] ExecIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “0?Set(CALLTYPE=)”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [exten@sub-record-check:4] Set(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “CALLEE=dontcare”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [exten@sub-record-check:5] ExecIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “0?Set(CALLEE=dontcare)”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [exten@sub-record-check:6] GotoIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “1?callee”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx_builtins.c: Goto (sub-record-check,exten,11)
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [exten@sub-record-check:11] Gosub(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “recordcheck,1(dontcare,external,101)”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “Starting recording check against dontcare”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [recordcheck@sub-record-check:2] Goto(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “dontcare”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [recordcheck@sub-record-check:3] Return(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [exten@sub-record-check:12] Return(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-exten-vm:7] GotoIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “1?macrodial”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx_builtins.c: Goto (macro-exten-vm,s,13)
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-exten-vm:13] GosubIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “0?clrheader,1()”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-exten-vm:14] Macro(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “dial-one,15,HhTtr,101”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-dial-one:1] Set(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “DEXTEN=101”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-dial-one:2] ExecIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “0?Set(__EXTTOCALL=101)”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-dial-one:3] Set(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “DIALSTATUS_CW=”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-dial-one:4] GosubIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “0?screen,1()”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-dial-one:5] GosubIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “0?cf,1()”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-dial-one:6] GotoIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “1?skip1”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx_builtins.c: Goto (macro-dial-one,s,9)
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-dial-one:9] GotoIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “0?nodial”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-dial-one:10] GotoIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “0?continue”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-dial-one:11] Set(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “EXTHASCW=ENABLED”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-dial-one:12] GotoIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “0?next1:cwinusebusy”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx_builtins.c: Goto (macro-dial-one,s,24)
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-dial-one:24] GotoIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “0?next3:continue”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx_builtins.c: Goto (macro-dial-one,s,26)
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-dial-one:26] GotoIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “0?nodial”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-dial-one:27] GosubIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “1?dstring,1():dlocal,1()”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [dstring@macro-dial-one:1] Set(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “DSTRING=”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [dstring@macro-dial-one:2] Set(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “DEVICES=101”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [dstring@macro-dial-one:3] ExecIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “0?Return()”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [dstring@macro-dial-one:4] ExecIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “0?Set(DEVICES=01)”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [dstring@macro-dial-one:5] Set(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “LOOPCNT=1”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [dstring@macro-dial-one:6] Set(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “ITER=1”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [dstring@macro-dial-one:7] Set(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “THISDIAL=PJSIP/101”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [dstring@macro-dial-one:8] GotoIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “0?docheck”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [dstring@macro-dial-one:9] NoOp(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “Debug: Found PJSIP Destination PJSIP/101”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [dstring@macro-dial-one:10] GotoIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “0?doset”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [dstring@macro-dial-one:11] NoOp(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “Debug: Updating PJSIP Destination with PJSIP_DIAL_CONTACTS”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [dstring@macro-dial-one:12] Set(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “THISDIAL=PJSIP/101/sip:101@192.168.1.134:5060”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [dstring@macro-dial-one:13] ExecIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “0?Set(DIALSTATUS=CHANUNAVAIL)”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [dstring@macro-dial-one:14] GotoIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “0?skipset”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [dstring@macro-dial-one:15] Set(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “DSTRING=PJSIP/101/sip:101@192.168.1.134:5060&”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [dstring@macro-dial-one:16] Set(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “ITER=2”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [dstring@macro-dial-one:17] GotoIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “0?begin”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [dstring@macro-dial-one:18] ExecIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “0?Return()”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [dstring@macro-dial-one:19] Set(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “DSTRING=PJSIP/101/sip:101@192.168.1.134:5060”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [dstring@macro-dial-one:20] Return(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-dial-one:28] GotoIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “0?nodial”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-dial-one:29] GotoIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “0?skiptrace”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-dial-one:30] GosubIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “1?ctset,1():ctclear,1()”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [ctset@macro-dial-one:1] Set(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “DB(CALLTRACE/101)=12024558888”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [ctset@macro-dial-one:2] Return(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-dial-one:31] Set(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “D_OPTIONS=HhTtr”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-dial-one:32] GosubIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “0?func-set-sipheader,s,1(Alert-Info,)”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-dial-one:33] NoOp(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, "Blind Transfer: , Attended Transfer: , User: , Alert Info: ") in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-dial-one:34] ExecIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “0?Set(ALERT_INFO=)”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-dial-one:35] ExecIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “0?Set(ALERT_INFO=)”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-dial-one:36] ExecIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “0?Set(ALERT_INFO=)”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-dial-one:37] ExecIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “0?Set(ALERT_INFO=Normal;volume=)”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-dial-one:38] ExecIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “0?Set(ALERT_INFO=Normal;volume=)”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-dial-one:39] GosubIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “0?func-set-sipheader,s,1(Alert-Info,)”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-dial-one:40] ExecIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “0?Set(CHANNEL(musicclass)=)”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-dial-one:41] GosubIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “0?qwait,1()”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-dial-one:42] Set(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “__CWIGNORE=”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-dial-one:43] Set(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “__KEEPCID=TRUE”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-dial-one:44] GotoIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “0?usegoto,1”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-dial-one:45] GotoIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “1?godial”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx_builtins.c: Goto (macro-dial-one,s,50)
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-dial-one:50] Macro(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “dialout-one-predial-hook,”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-dialout-one-predial-hook:1] MacroExit(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-dial-one:51] ExecIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “1?Set(D_OPTIONS=HhtrI)”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-dial-one:52] ExecIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “0?Set(CWRING=r(callwaiting)):Set(CWRING=)”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-dial-one:53] NoOp(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-dial-one:54] ExecIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “0?Set(D_OPTIONS=HhtrIg)”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-dial-one:55] Dial(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “PJSIP/101/sip:101@192.168.1.134:5060,15,HhtrIb(func-apply-sipheaders^s^1)”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] app_stack.c: PJSIP/101-00000008 Internal Gosub(func-apply-sipheaders,s,1) start
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@func-apply-sipheaders:1] NoOp(“PJSIP/101-00000008”, “Applying SIP Headers to channel PJSIP/101-00000008”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@func-apply-sipheaders:2] Set(“PJSIP/101-00000008”, “TECH=PJSIP”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@func-apply-sipheaders:3] Set(“PJSIP/101-00000008”, “SIPHEADERKEYS=”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@func-apply-sipheaders:4] While(“PJSIP/101-00000008”, “0”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] app_while.c: Jumping to priority 12
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] pbx.c: Executing [s@func-apply-sipheaders:13] Return(“PJSIP/101-00000008”, “”) in new stack
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] app_stack.c: Spawn extension (from-internal, 101, 1) exited non-zero on ‘PJSIP/101-00000008’
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] app_stack.c: PJSIP/101-00000008 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] app_dial.c: Called PJSIP/101/sip:101@192.168.1.134:5060
[2019-11-15 15:50:25] VERBOSE[11658] netsock2.c: Using SIP RTP Audio TOS bits 184
[2019-11-15 15:50:25] VERBOSE[11658] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field.
[2019-11-15 15:50:25] VERBOSE[11658] netsock2.c: Using SIP RTP Audio CoS mark 5
[2019-11-15 15:50:25] VERBOSE[30715][C-00000009] app_dial.c: Connected line update to SIP/fpbx-1-Rm11PwGVRp4i-00000008 prevented.
[2019-11-15 15:50:26] VERBOSE[30715][C-00000009] app_dial.c: PJSIP/101-00000008 is ringing
[2019-11-15 15:50:26] VERBOSE[30715][C-00000009] app_dial.c: PJSIP/101-00000008 is ringing
[2019-11-15 15:50:26] VERBOSE[30715][C-00000009] app_macro.c: Spawn extension (macro-dial-one, s, 55) exited non-zero on ‘SIP/fpbx-1-Rm11PwGVRp4i-00000008’ in macro ‘dial-one’
[2019-11-15 15:50:26] VERBOSE[30715][C-00000009] app_macro.c: Spawn extension (macro-exten-vm, s, 14) exited non-zero on ‘SIP/fpbx-1-Rm11PwGVRp4i-00000008’ in macro ‘exten-vm’
[2019-11-15 15:50:26] VERBOSE[30715][C-00000009] pbx.c: Spawn extension (from-did-direct, 101, 2) exited non-zero on ‘SIP/fpbx-1-Rm11PwGVRp4i-00000008’
[2019-11-15 15:50:26] VERBOSE[30715][C-00000009] pbx.c: Executing [h@from-did-direct:1] Macro(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “hangupcall,”) in new stack
[2019-11-15 15:50:26] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-hangupcall:1] GotoIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “1?theend”) in new stack
[2019-11-15 15:50:26] VERBOSE[30715][C-00000009] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2019-11-15 15:50:26] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-hangupcall:3] ExecIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “0?Set(CDR(recordingfile)=)”) in new stack
[2019-11-15 15:50:26] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-hangupcall:4] NoOp(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, "PJSIP/101-00000008 montior file= ") in new stack
[2019-11-15 15:50:26] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-hangupcall:5] GotoIf(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “1?skipagi”) in new stack
[2019-11-15 15:50:26] VERBOSE[30715][C-00000009] pbx_builtins.c: Goto (macro-hangupcall,s,7)
[2019-11-15 15:50:26] VERBOSE[30715][C-00000009] pbx.c: Executing [s@macro-hangupcall:7] Hangup(“SIP/fpbx-1-Rm11PwGVRp4i-00000008”, “”) in new stack
[2019-11-15 15:50:26] VERBOSE[30715][C-00000009] app_macro.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on ‘SIP/fpbx-1-Rm11PwGVRp4i-00000008’ in macro ‘hangupcall’
[2019-11-15 15:50:26] VERBOSE[30715][C-00000009] pbx.c: Spawn extension (from-did-direct, h, 1) exited non-zero on ‘SIP/fpbx-1-Rm11PwGVRp4i-00000008’