Incoming call beep when user is on phone

jessienunez1989 · July 23, 2018, 2:20pm

I have built a few freepbx machines using a dell optiplex 7060 and a TDM410p asterisk card using Freepbx 12.0.76.6. I am somewhat confident in setting these systems up but for the life of me I have never ran thru this situation before. My current setup I have configured the Trunks/inbound route/outbound route no issues. Calls can come in and out. My issue is when a user is on the phone and another call comes in the user who is on the phone would hear an incoming call beep and the secondary phone will not ring. I configured the ring group to skip busy agents but still the same problem. I am not sure if this helps but the phones connect to the freepbx using NAT and I use OSS Endpoint Manager with Cisco IP 7960 phones.

cynjut · July 23, 2018, 4:23pm

Check your “Call Waiting” status. If you are using Ring Groups, you should probably not use CW at all.

jessienunez1989 · July 23, 2018, 4:45pm

@cynjut Thanks for the reply, I disabled call waiting on the extensions themselves. Later on when I arrive home I will disabled call waiting on the phone as well and test. I will give you a heads up on how it worked out.

jessienunez1989 · July 24, 2018, 1:04am

@cynjut unfortunately same issue. I still receive the beep noise when another incoming call is coming.

PitzKey · July 24, 2018, 6:26pm

I believe you did, but we still ask… Did you apply config?

Post a call log.

jessienunez1989 · July 24, 2018, 6:38pm

@PitzKey Thanks for chiming in, and yes I applied on both the Cisco Phones and Freepbx. Here is the following log;

[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Goto (from-analog,s,9)
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@from-analog:9] NoOp(“DAHDI/1-1”, “Is a DAHDi Channel”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@from-analog:10] Set(“DAHDI/1-1”, “CHAN=1-1”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@from-analog:11] Set(“DAHDI/1-1”, “CHAN=1”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@from-analog:12] Macro(“DAHDI/1-1”, “from-dahdi-1,s,1”) in new stack
[2018-07-22 12:21:08] WARNING[23591][C-00000027] app_macro.c: No such context ‘macro-from-dahdi-1’ for macro ‘from-dahdi-1’. Was called by s@from-analog
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@from-analog:13] NoOp(“DAHDI/1-1”, “Returned from Macro from-dahdi-1”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@from-analog:14] Goto(“DAHDI/1-1”, “from-pstn,s,1”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Goto (from-pstn,s,1)
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@from-pstn:1] Set(“DAHDI/1-1”, “__DIRECTION=INBOUND”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@from-pstn:2] Gosub(“DAHDI/1-1”, “sub-record-check,s,1(in,s,dontcare)”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@sub-record-check:1] GotoIf(“DAHDI/1-1”, “0?initialized”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@sub-record-check:2] Set(“DAHDI/1-1”, “__REC_STATUS=INITIALIZED”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@sub-record-check:3] Set(“DAHDI/1-1”, “NOW=1532276468”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@sub-record-check:4] Set(“DAHDI/1-1”, “__DAY=22”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@sub-record-check:5] Set(“DAHDI/1-1”, “__MONTH=07”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@sub-record-check:6] Set(“DAHDI/1-1”, “__YEAR=2018”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@sub-record-check:7] Set(“DAHDI/1-1”, “__TIMESTR=20180722-122108”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@sub-record-check:8] Set(“DAHDI/1-1”, “__FROMEXTEN=unknown”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@sub-record-check:9] Set(“DAHDI/1-1”, “__MON_FMT=wav”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@sub-record-check:10] NoOp(“DAHDI/1-1”, “Recordings initialized”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@sub-record-check:11] ExecIf(“DAHDI/1-1”, “0?Set(ARG3=dontcare)”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@sub-record-check:12] Set(“DAHDI/1-1”, “REC_POLICY_MODE_SAVE=”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@sub-record-check:13] ExecIf(“DAHDI/1-1”, “0?Set(REC_STATUS=NO)”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@sub-record-check:14] GotoIf(“DAHDI/1-1”, “2?checkaction”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Goto (sub-record-check,s,17)
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@sub-record-check:17] GotoIf(“DAHDI/1-1”, “1?sub-record-check,in,1”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Goto (sub-record-check,in,1)
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [in@sub-record-check:1] NoOp(“DAHDI/1-1”, “Inbound Recording Check to s”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [in@sub-record-check:2] Set(“DAHDI/1-1”, “FROMEXTEN=unknown”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [in@sub-record-check:3] ExecIf(“DAHDI/1-1”, “0?Set(FROMEXTEN=)”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [in@sub-record-check:4] Gosub(“DAHDI/1-1”, “recordcheck,1(dontcare,in,s)”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [recordcheck@sub-record-check:1] NoOp(“DAHDI/1-1”, “Starting recording check against dontcare”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [recordcheck@sub-record-check:2] Goto(“DAHDI/1-1”, “dontcare”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Goto (sub-record-check,recordcheck,3)
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [recordcheck@sub-record-check:3] Return(“DAHDI/1-1”, “”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [in@sub-record-check:5] Return(“DAHDI/1-1”, “”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@from-pstn:3] Gosub(“DAHDI/1-1”, “app-blacklist-check,s,1()”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@app-blacklist-check:1] GotoIf(“DAHDI/1-1”, “0?blacklisted”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@app-blacklist-check:2] Set(“DAHDI/1-1”, “CALLED_BLACKLIST=1”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@app-blacklist-check:3] Return(“DAHDI/1-1”, “”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@from-pstn:4] ExecIf(“DAHDI/1-1”, “1?Set(__FROM_DID=s)”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@from-pstn:5] Set(“DAHDI/1-1”, “CDR(did)=s”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@from-pstn:6] ExecIf(“DAHDI/1-1”, “1 ?Set(CALLERID(name)=)”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@from-pstn:7] Set(“DAHDI/1-1”, “CHANNEL(musicclass)=default”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@from-pstn:8] Set(“DAHDI/1-1”, “__MOHCLASS=default”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@from-pstn:9] Ringing(“DAHDI/1-1”, “”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@from-pstn:10] Set(“DAHDI/1-1”, “__RINGINGSENT=TRUE”) in new stack
[2018-07-22 12:21:08] WARNING[23591][C-00000027] func_channel.c: Unknown or unavailable item requested: ‘reversecharge’
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@from-pstn:11] GotoIf(“DAHDI/1-1”, “0?macro-hangupcall”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@from-pstn:12] Set(“DAHDI/1-1”, “__CALLINGPRES_SV=allowed_not_screened”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@from-pstn:13] Set(“DAHDI/1-1”, “CALLERPRES()=allowed_not_screened”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@from-pstn:14] Goto(“DAHDI/1-1”, “ext-group,1,1”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Goto (ext-group,1,1)
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [1@ext-group:1] Macro(“DAHDI/1-1”, “user-callerid,”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@macro-user-callerid:1] Set(“DAHDI/1-1”, “TOUCH_MONITOR=1532276458.49”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@macro-user-callerid:2] Set(“DAHDI/1-1”, “AMPUSER=”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@macro-user-callerid:3] GotoIf(“DAHDI/1-1”, “0?report”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@macro-user-callerid:4] ExecIf(“DAHDI/1-1”, “1?Set(REALCALLERIDNUM=)”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@macro-user-callerid:5] Set(“DAHDI/1-1”, “AMPUSER=”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@macro-user-callerid:6] GotoIf(“DAHDI/1-1”, “0?limit”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@macro-user-callerid:7] Set(“DAHDI/1-1”, “AMPUSERCIDNAME=”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@macro-user-callerid:8] GotoIf(“DAHDI/1-1”, “1?report”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Goto (macro-user-callerid,s,16)
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@macro-user-callerid:16] GotoIf(“DAHDI/1-1”, “0?continue”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@macro-user-callerid:17] ExecIf(“DAHDI/1-1”, “1?Set(__CALLEE_ACCOUNCODE=)”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@macro-user-callerid:18] Set(“DAHDI/1-1”, “__TTL=64”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@macro-user-callerid:19] GotoIf(“DAHDI/1-1”, “1?continue”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Goto (macro-user-callerid,s,30)
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@macro-user-callerid:30] Set(“DAHDI/1-1”, “CALLERID(number)=”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@macro-user-callerid:31] Set(“DAHDI/1-1”, “CALLERID(name)=”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@macro-user-callerid:32] Set(“DAHDI/1-1”, “CDR(cnum)=”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@macro-user-callerid:33] Set(“DAHDI/1-1”, “CDR(cnam)=”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@macro-user-callerid:34] Set(“DAHDI/1-1”, “CHANNEL(language)=en”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [1@ext-group:2] Macro(“DAHDI/1-1”, “blkvm-setifempty,”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@macro-blkvm-setifempty:1] GotoIf(“DAHDI/1-1”, “1?init”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Goto (macro-blkvm-setifempty,s,4)
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@macro-blkvm-setifempty:4] Set(“DAHDI/1-1”, “__BLKVM_CHANNEL=DAHDI/1-1”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@macro-blkvm-setifempty:5] Set(“DAHDI/1-1”, “SHARED(BLKVM,DAHDI/1-1)=TRUE”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@macro-blkvm-setifempty:6] Set(“DAHDI/1-1”, “GOSUB_RETVAL=TRUE”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@macro-blkvm-setifempty:7] MacroExit(“DAHDI/1-1”, “”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [1@ext-group:3] GotoIf(“DAHDI/1-1”, “1?skipov”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Goto (ext-group,1,6)
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [1@ext-group:6] Set(“DAHDI/1-1”, “RRNODEST=”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [1@ext-group:7] Set(“DAHDI/1-1”, “__NODEST=1”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [1@ext-group:8] GosubIf(“DAHDI/1-1”, “0?sub-rgsetcid,s,1()”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [1@ext-group:9] Set(“DAHDI/1-1”, “__CWIGNORE=TRUE”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [1@ext-group:10] Gosub(“DAHDI/1-1”, “sub-record-check,s,1(rg,1,dontcare)”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@sub-record-check:1] GotoIf(“DAHDI/1-1”, “7?initialized”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Goto (sub-record-check,s,10)
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@sub-record-check:10] NoOp(“DAHDI/1-1”, “Recordings initialized”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@sub-record-check:11] ExecIf(“DAHDI/1-1”, “0?Set(ARG3=dontcare)”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@sub-record-check:12] Set(“DAHDI/1-1”, “REC_POLICY_MODE_SAVE=”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@sub-record-check:13] ExecIf(“DAHDI/1-1”, “0?Set(REC_STATUS=NO)”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@sub-record-check:14] GotoIf(“DAHDI/1-1”, “2?checkaction”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Goto (sub-record-check,s,17)
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@sub-record-check:17] GotoIf(“DAHDI/1-1”, “0?sub-record-check,rg,1”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@sub-record-check:18] NoOp(“DAHDI/1-1”, “Generic rg Recording Check - unknown 1”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@sub-record-check:19] Gosub(“DAHDI/1-1”, “recordcheck,1(dontcare,rg,1)”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [recordcheck@sub-record-check:1] NoOp(“DAHDI/1-1”, “Starting recording check against dontcare”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [recordcheck@sub-record-check:2] Goto(“DAHDI/1-1”, “dontcare”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Goto (sub-record-check,recordcheck,3)
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [recordcheck@sub-record-check:3] Return(“DAHDI/1-1”, “”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@sub-record-check:20] Return(“DAHDI/1-1”, “”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [1@ext-group:11] Set(“DAHDI/1-1”, “RingGroupMethod=ringall”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [1@ext-group:12] Macro(“DAHDI/1-1”, “dial,20,Ttr,4-5”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@macro-dial:1] GotoIf(“DAHDI/1-1”, “0?dial”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@macro-dial:2] Set(“DAHDI/1-1”, “CHANNEL(musicclass)=default”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@macro-dial:3] AGI(“DAHDI/1-1”, “dialparties.agi”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] res_agi.c: – Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] res_agi.c: dialparties.agi: Starting New Dialparties.agi
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] res_agi.c: dialparties.agi: Caller ID name is ‘unknown’ number is ‘unknown’
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] res_agi.c: dialparties.agi: Methodology of ring is ‘ringall’
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] res_agi.c: – dialparties.agi: Added extension 4 to extension map
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] res_agi.c: – dialparties.agi: Added extension 5 to extension map
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] res_agi.c: – dialparties.agi: Extension 4 cf is disabled
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] res_agi.c: – dialparties.agi: Extension 5 cf is disabled
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] res_agi.c: – dialparties.agi: Extension 4 do not disturb is disabled
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] res_agi.c: – dialparties.agi: Extension 5 do not disturb is disabled
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] res_agi.c: dialparties.agi: EXTENSION_STATE: 4 (UNAVAILABLE)
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] res_agi.c: dialparties.agi: Extension 4 has ExtensionState: 4
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] res_agi.c: – dialparties.agi: Checking CW and CFB status for extension 4
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] res_agi.c: – dialparties.agi: DbDel CALLTRACE/4 - Caller ID is not defined
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] res_agi.c: dialparties.agi: EXTENSION_STATE: 4 (UNAVAILABLE)
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] res_agi.c: dialparties.agi: Extension 5 has ExtensionState: 4
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] res_agi.c: – dialparties.agi: Checking CW and CFB status for extension 5
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] res_agi.c: – dialparties.agi: DbDel CALLTRACE/5 - Caller ID is not defined
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] res_agi.c: – dialparties.agi: Filtered ARG3: 4-5
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] res_agi.c: – <DAHDI/1-1>AGI Script dialparties.agi completed, returning 0
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@macro-dial:7] Dial(“DAHDI/1-1”, “SIP/4&SIP/5,20,trM(auto-blkvm)”) in new stack
[2018-07-22 12:21:08] WARNING[23591][C-00000027] app_dial.c: Unable to create channel of type ‘SIP’ (cause 20 - Subscriber absent)
[2018-07-22 12:21:08] WARNING[23591][C-00000027] app_dial.c: Unable to create channel of type ‘SIP’ (cause 20 - Subscriber absent)
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] app_dial.c: == Everyone is busy/congested at this time (2:0/0/2)
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@macro-dial:8] Set(“DAHDI/1-1”, “DIALSTATUS=CHANUNAVAIL”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@macro-dial:9] GosubIf(“DAHDI/1-1”, “0?CHANUNAVAIL,1”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [1@ext-group:13] Gosub(“DAHDI/1-1”, “sub-record-cancel,s,1()”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@sub-record-cancel:1] Return(“DAHDI/1-1”, “”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [1@ext-group:14] Set(“DAHDI/1-1”, “RingGroupMethod=”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [1@ext-group:15] GotoIf(“DAHDI/1-1”, “0?nodest”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [1@ext-group:16] Set(“DAHDI/1-1”, “__CWIGNORE=”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [1@ext-group:17] Set(“DAHDI/1-1”, “__NODEST=”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [1@ext-group:18] Macro(“DAHDI/1-1”, “blkvm-clr,”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@macro-blkvm-clr:1] Set(“DAHDI/1-1”, “SHARED(BLKVM,DAHDI/1-1)=”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@macro-blkvm-clr:2] Set(“DAHDI/1-1”, “GOSUB_RETVAL=”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [s@macro-blkvm-clr:3] MacroExit(“DAHDI/1-1”, “”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [1@ext-group:19] Goto(“DAHDI/1-1”, “app-blackhole,hangup,1”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Goto (app-blackhole,hangup,1)
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [hangup@app-blackhole:1] NoOp(“DAHDI/1-1”, “Blackhole Dest: Hangup”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: – Executing [hangup@app-blackhole:2] Hangup(“DAHDI/1-1”, “”) in new stack
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] pbx.c: == Spawn extension (app-blackhole, hangup, 2) exited non-zero on ‘DAHDI/1-1’
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] sig_analog.c: – Hanging up on ‘DAHDI/1-1’
[2018-07-22 12:21:08] VERBOSE[23591][C-00000027] chan_dahdi.c: – Hungup ‘DAHDI/1-1’
[2018-07-22 12:26:53] VERBOSE[2401] chan_sip.c: – Registered SIP ‘5’ at 10.0.6.200:5060
[2018-07-22 12:26:53] NOTICE[2401] chan_sip.c: Peer ‘5’ is now Reachable. (186ms / 2000ms)
[2018-07-22 12:31:22] VERBOSE[23882][C-00000028] sig_analog.c: – Starting simple switch on ‘DAHDI/1-1’
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@from-analog:1] NoOp(“DAHDI/1-1”, "Entering from-dahdi with DID == ") in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@from-analog:2] Ringing(“DAHDI/1-1”, “”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@from-analog:3] Set(“DAHDI/1-1”, “DID=s”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@from-analog:4] NoOp(“DAHDI/1-1”, “DID is now s”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@from-analog:5] GotoIf(“DAHDI/1-1”, “1?dahdiok:checkzap”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Goto (from-analog,s,9)
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@from-analog:9] NoOp(“DAHDI/1-1”, “Is a DAHDi Channel”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@from-analog:10] Set(“DAHDI/1-1”, “CHAN=1-1”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@from-analog:11] Set(“DAHDI/1-1”, “CHAN=1”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@from-analog:12] Macro(“DAHDI/1-1”, “from-dahdi-1,s,1”) in new stack
[2018-07-22 12:31:23] WARNING[23882][C-00000028] app_macro.c: No such context ‘macro-from-dahdi-1’ for macro ‘from-dahdi-1’. Was called by s@from-analog
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@from-analog:13] NoOp(“DAHDI/1-1”, “Returned from Macro from-dahdi-1”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@from-analog:14] Goto(“DAHDI/1-1”, “from-pstn,s,1”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Goto (from-pstn,s,1)
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@from-pstn:1] Set(“DAHDI/1-1”, “__DIRECTION=INBOUND”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@from-pstn:2] Gosub(“DAHDI/1-1”, “sub-record-check,s,1(in,s,dontcare)”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@sub-record-check:1] GotoIf(“DAHDI/1-1”, “0?initialized”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@sub-record-check:2] Set(“DAHDI/1-1”, “__REC_STATUS=INITIALIZED”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@sub-record-check:3] Set(“DAHDI/1-1”, “NOW=1532277083”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@sub-record-check:4] Set(“DAHDI/1-1”, “__DAY=22”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@sub-record-check:5] Set(“DAHDI/1-1”, “__MONTH=07”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@sub-record-check:6] Set(“DAHDI/1-1”, “__YEAR=2018”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@sub-record-check:7] Set(“DAHDI/1-1”, “__TIMESTR=20180722-123123”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@sub-record-check:8] Set(“DAHDI/1-1”, “__FROMEXTEN=unknown”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@sub-record-check:9] Set(“DAHDI/1-1”, “__MON_FMT=wav”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@sub-record-check:10] NoOp(“DAHDI/1-1”, “Recordings initialized”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@sub-record-check:11] ExecIf(“DAHDI/1-1”, “0?Set(ARG3=dontcare)”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@sub-record-check:12] Set(“DAHDI/1-1”, “REC_POLICY_MODE_SAVE=”) in new stack

jessienunez1989 · July 24, 2018, 6:39pm

[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@sub-record-check:13] ExecIf(“DAHDI/1-1”, “0?Set(REC_STATUS=NO)”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@sub-record-check:14] GotoIf(“DAHDI/1-1”, “2?checkaction”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Goto (sub-record-check,s,17)
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@sub-record-check:17] GotoIf(“DAHDI/1-1”, “1?sub-record-check,in,1”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Goto (sub-record-check,in,1)
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [in@sub-record-check:1] NoOp(“DAHDI/1-1”, “Inbound Recording Check to s”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [in@sub-record-check:2] Set(“DAHDI/1-1”, “FROMEXTEN=unknown”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [in@sub-record-check:3] ExecIf(“DAHDI/1-1”, “10?Set(FROMEXTEN=8772763721)”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [in@sub-record-check:4] Gosub(“DAHDI/1-1”, “recordcheck,1(dontcare,in,s)”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [recordcheck@sub-record-check:1] NoOp(“DAHDI/1-1”, “Starting recording check against dontcare”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [recordcheck@sub-record-check:2] Goto(“DAHDI/1-1”, “dontcare”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Goto (sub-record-check,recordcheck,3)
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [recordcheck@sub-record-check:3] Return(“DAHDI/1-1”, “”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [in@sub-record-check:5] Return(“DAHDI/1-1”, “”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@from-pstn:3] Gosub(“DAHDI/1-1”, “app-blacklist-check,s,1()”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@app-blacklist-check:1] GotoIf(“DAHDI/1-1”, “0?blacklisted”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@app-blacklist-check:2] Set(“DAHDI/1-1”, “CALLED_BLACKLIST=1”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@app-blacklist-check:3] Return(“DAHDI/1-1”, “”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@from-pstn:4] ExecIf(“DAHDI/1-1”, “1?Set(__FROM_DID=s)”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@from-pstn:5] Set(“DAHDI/1-1”, “CDR(did)=s”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@from-pstn:6] ExecIf(“DAHDI/1-1”, “0 ?Set(CALLERID(name)=8772763721)”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@from-pstn:7] Set(“DAHDI/1-1”, “CHANNEL(musicclass)=default”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@from-pstn:8] Set(“DAHDI/1-1”, “__MOHCLASS=default”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@from-pstn:9] Ringing(“DAHDI/1-1”, “”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@from-pstn:10] Set(“DAHDI/1-1”, “__RINGINGSENT=TRUE”) in new stack
[2018-07-22 12:31:23] WARNING[23882][C-00000028] func_channel.c: Unknown or unavailable item requested: ‘reversecharge’
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@from-pstn:11] GotoIf(“DAHDI/1-1”, “0?macro-hangupcall”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@from-pstn:12] Set(“DAHDI/1-1”, “__CALLINGPRES_SV=allowed_not_screened”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@from-pstn:13] Set(“DAHDI/1-1”, “CALLERPRES()=allowed_not_screened”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@from-pstn:14] Goto(“DAHDI/1-1”, “ext-group,1,1”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Goto (ext-group,1,1)
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [1@ext-group:1] Macro(“DAHDI/1-1”, “user-callerid,”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@macro-user-callerid:1] Set(“DAHDI/1-1”, “TOUCH_MONITOR=1532277082.50”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@macro-user-callerid:2] Set(“DAHDI/1-1”, “AMPUSER=8772763721”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@macro-user-callerid:3] GotoIf(“DAHDI/1-1”, “0?report”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@macro-user-callerid:4] ExecIf(“DAHDI/1-1”, “1?Set(REALCALLERIDNUM=8772763721)”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@macro-user-callerid:5] Set(“DAHDI/1-1”, “AMPUSER=”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@macro-user-callerid:6] GotoIf(“DAHDI/1-1”, “0?limit”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@macro-user-callerid:7] Set(“DAHDI/1-1”, “AMPUSERCIDNAME=”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@macro-user-callerid:8] GotoIf(“DAHDI/1-1”, “1?report”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Goto (macro-user-callerid,s,16)
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@macro-user-callerid:16] GotoIf(“DAHDI/1-1”, “0?continue”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@macro-user-callerid:17] ExecIf(“DAHDI/1-1”, “1?Set(__CALLEE_ACCOUNCODE=)”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@macro-user-callerid:18] Set(“DAHDI/1-1”, “__TTL=64”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@macro-user-callerid:19] GotoIf(“DAHDI/1-1”, “1?continue”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Goto (macro-user-callerid,s,30)
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@macro-user-callerid:30] Set(“DAHDI/1-1”, “CALLERID(number)=8772763721”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@macro-user-callerid:31] Set(“DAHDI/1-1”, “CALLERID(name)=CU CARDSECURITY”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@macro-user-callerid:32] Set(“DAHDI/1-1”, “CDR(cnum)=8772763721”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@macro-user-callerid:33] Set(“DAHDI/1-1”, “CDR(cnam)=CU CARDSECURITY”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@macro-user-callerid:34] Set(“DAHDI/1-1”, “CHANNEL(language)=en”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [1@ext-group:2] Macro(“DAHDI/1-1”, “blkvm-setifempty,”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@macro-blkvm-setifempty:1] GotoIf(“DAHDI/1-1”, “1?init”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Goto (macro-blkvm-setifempty,s,4)
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@macro-blkvm-setifempty:4] Set(“DAHDI/1-1”, “__BLKVM_CHANNEL=DAHDI/1-1”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@macro-blkvm-setifempty:5] Set(“DAHDI/1-1”, “SHARED(BLKVM,DAHDI/1-1)=TRUE”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@macro-blkvm-setifempty:6] Set(“DAHDI/1-1”, “GOSUB_RETVAL=TRUE”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@macro-blkvm-setifempty:7] MacroExit(“DAHDI/1-1”, “”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [1@ext-group:3] GotoIf(“DAHDI/1-1”, “1?skipov”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Goto (ext-group,1,6)
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [1@ext-group:6] Set(“DAHDI/1-1”, “RRNODEST=”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [1@ext-group:7] Set(“DAHDI/1-1”, “__NODEST=1”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [1@ext-group:8] GosubIf(“DAHDI/1-1”, “0?sub-rgsetcid,s,1()”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [1@ext-group:9] Set(“DAHDI/1-1”, “__CWIGNORE=TRUE”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [1@ext-group:10] Gosub(“DAHDI/1-1”, “sub-record-check,s,1(rg,1,dontcare)”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@sub-record-check:1] GotoIf(“DAHDI/1-1”, “10?initialized”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Goto (sub-record-check,s,10)
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@sub-record-check:10] NoOp(“DAHDI/1-1”, “Recordings initialized”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@sub-record-check:11] ExecIf(“DAHDI/1-1”, “0?Set(ARG3=dontcare)”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@sub-record-check:12] Set(“DAHDI/1-1”, “REC_POLICY_MODE_SAVE=”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@sub-record-check:13] ExecIf(“DAHDI/1-1”, “0?Set(REC_STATUS=NO)”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@sub-record-check:14] GotoIf(“DAHDI/1-1”, “2?checkaction”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Goto (sub-record-check,s,17)
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@sub-record-check:17] GotoIf(“DAHDI/1-1”, “0?sub-record-check,rg,1”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@sub-record-check:18] NoOp(“DAHDI/1-1”, “Generic rg Recording Check - 8772763721 1”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@sub-record-check:19] Gosub(“DAHDI/1-1”, “recordcheck,1(dontcare,rg,1)”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [recordcheck@sub-record-check:1] NoOp(“DAHDI/1-1”, “Starting recording check against dontcare”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [recordcheck@sub-record-check:2] Goto(“DAHDI/1-1”, “dontcare”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Goto (sub-record-check,recordcheck,3)
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [recordcheck@sub-record-check:3] Return(“DAHDI/1-1”, “”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@sub-record-check:20] Return(“DAHDI/1-1”, “”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [1@ext-group:11] Set(“DAHDI/1-1”, “RingGroupMethod=ringall”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [1@ext-group:12] Macro(“DAHDI/1-1”, “dial,20,Ttr,4-5”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@macro-dial:1] GotoIf(“DAHDI/1-1”, “0?dial”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@macro-dial:2] Set(“DAHDI/1-1”, “CHANNEL(musicclass)=default”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@macro-dial:3] AGI(“DAHDI/1-1”, “dialparties.agi”) in new stack
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] res_agi.c: – Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] res_agi.c: dialparties.agi: Starting New Dialparties.agi
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] res_agi.c: dialparties.agi: Caller ID name is ‘CU CARDSECURITY’ number is ‘8772763721’
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] res_agi.c: dialparties.agi: Methodology of ring is ‘ringall’
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] res_agi.c: – dialparties.agi: Added extension 4 to extension map
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] res_agi.c: – dialparties.agi: Added extension 5 to extension map
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] res_agi.c: – dialparties.agi: Extension 4 cf is disabled
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] res_agi.c: – dialparties.agi: Extension 5 cf is disabled
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] res_agi.c: – dialparties.agi: Extension 4 do not disturb is disabled
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] res_agi.c: – dialparties.agi: Extension 5 do not disturb is disabled
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] res_agi.c: dialparties.agi: EXTENSION_STATE: 4 (UNAVAILABLE)
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] res_agi.c: dialparties.agi: Extension 4 has ExtensionState: 4
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] res_agi.c: – dialparties.agi: Checking CW and CFB status for extension 4
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] res_agi.c: – dialparties.agi: dbset CALLTRACE/4 to 8772763721
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] res_agi.c: dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE)
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] res_agi.c: dialparties.agi: Extension 5 has ExtensionState: 0
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] res_agi.c: – dialparties.agi: Checking CW and CFB status for extension 5
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] res_agi.c: – dialparties.agi: dbset CALLTRACE/5 to 8772763721
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] res_agi.c: – dialparties.agi: Filtered ARG3: 4-5
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] res_agi.c: – <DAHDI/1-1>AGI Script dialparties.agi completed, returning 0
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@macro-dial:7] Dial(“DAHDI/1-1”, “SIP/4&SIP/5,20,trM(auto-blkvm)”) in new stack
[2018-07-22 12:31:23] WARNING[23882][C-00000028] app_dial.c: Unable to create channel of type ‘SIP’ (cause 20 - Subscriber absent)
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] netsock2.c: == Using SIP RTP TOS bits 184
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] netsock2.c: == Using SIP RTP CoS mark 5
[2018-07-22 12:31:23] VERBOSE[23882][C-00000028] app_dial.c: – Called SIP/5
[2018-07-22 12:31:24] VERBOSE[23882][C-00000028] app_dial.c: – SIP/5-00000015 is ringing
[2018-07-22 12:31:43] VERBOSE[23882][C-00000028] app_dial.c: – Nobody picked up in 20000 ms
[2018-07-22 12:31:43] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@macro-dial:8] Set(“DAHDI/1-1”, “DIALSTATUS=NOANSWER”) in new stack
[2018-07-22 12:31:43] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@macro-dial:9] GosubIf(“DAHDI/1-1”, “0?NOANSWER,1”) in new stack
[2018-07-22 12:31:43] VERBOSE[23882][C-00000028] pbx.c: – Executing [1@ext-group:13] Gosub(“DAHDI/1-1”, “sub-record-cancel,s,1()”) in new stack
[2018-07-22 12:31:43] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@sub-record-cancel:1] Return(“DAHDI/1-1”, “”) in new stack
[2018-07-22 12:31:43] VERBOSE[23882][C-00000028] pbx.c: – Executing [1@ext-group:14] Set(“DAHDI/1-1”, “RingGroupMethod=”) in new stack
[2018-07-22 12:31:43] VERBOSE[23882][C-00000028] pbx.c: – Executing [1@ext-group:15] GotoIf(“DAHDI/1-1”, “0?nodest”) in new stack
[2018-07-22 12:31:43] VERBOSE[23882][C-00000028] pbx.c: – Executing [1@ext-group:16] Set(“DAHDI/1-1”, “__CWIGNORE=”) in new stack
[2018-07-22 12:31:43] VERBOSE[23882][C-00000028] pbx.c: – Executing [1@ext-group:17] Set(“DAHDI/1-1”, “__NODEST=”) in new stack
[2018-07-22 12:31:43] VERBOSE[23882][C-00000028] pbx.c: – Executing [1@ext-group:18] Macro(“DAHDI/1-1”, “blkvm-clr,”) in new stack
[2018-07-22 12:31:43] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@macro-blkvm-clr:1] Set(“DAHDI/1-1”, “SHARED(BLKVM,DAHDI/1-1)=”) in new stack
[2018-07-22 12:31:43] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@macro-blkvm-clr:2] Set(“DAHDI/1-1”, “GOSUB_RETVAL=”) in new stack
[2018-07-22 12:31:43] VERBOSE[23882][C-00000028] pbx.c: – Executing [s@macro-blkvm-clr:3] MacroExit(“DAHDI/1-1”, “”) in new stack
[2018-07-22 12:31:43] VERBOSE[23882][C-00000028] pbx.c: – Executing [1@ext-group:19] Goto(“DAHDI/1-1”, “app-blackhole,hangup,1”) in new stack
[2018-07-22 12:31:43] VERBOSE[23882][C-00000028] pbx.c: – Goto (app-blackhole,hangup,1)
[2018-07-22 12:31:43] VERBOSE[23882][C-00000028] pbx.c: – Executing [hangup@app-blackhole:1] NoOp(“DAHDI/1-1”, “Blackhole Dest: Hangup”) in new stack
[2018-07-22 12:31:43] VERBOSE[23882][C-00000028] pbx.c: – Executing [hangup@app-blackhole:2] Hangup(“DAHDI/1-1”, “”) in new stack
[2018-07-22 12:31:43] VERBOSE[23882][C-00000028] pbx.c: == Spawn extension (app-blackhole, hangup, 2) exited non-zero on ‘DAHDI/1-1’
[2018-07-22 12:31:43] VERBOSE[23882][C-00000028] sig_analog.c: – Hanging up on ‘DAHDI/1-1’
[2018-07-22 12:31:43] VERBOSE[23882][C-00000028] chan_dahdi.c: – Hungup ‘DAHDI/1-1’
[2018-07-22 12:31:46] VERBOSE[23884][C-00000029] sig_analog.c: – Starting simple switch on ‘DAHDI/1-1’
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: – Executing [s@from-analog:1] NoOp(“DAHDI/1-1”, "Entering from-dahdi with DID == ") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: – Executing [s@from-analog:2] Ringing(“DAHDI/1-1”, “”) in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: – Executing [s@from-analog:3] Set(“DAHDI/1-1”, “DID=s”) in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: – Executing [s@from-analog:4] NoOp(“DAHDI/1-1”, “DID is now s”) in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: – Executing [s@from-analog:5] GotoIf(“DAHDI/1-1”, “1?dahdiok:checkzap”) in new stack

jessienunez1989 · July 24, 2018, 6:40pm

[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Goto (from-analog,s,9)
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@from-analog:9] NoOp("DAHDI/1-1", "Is a DAHDi Channel") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@from-analog:10] Set("DAHDI/1-1", "CHAN=1-1") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@from-analog:11] Set("DAHDI/1-1", "CHAN=1") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@from-analog:12] Macro("DAHDI/1-1", "from-dahdi-1,s,1") in new stack
[2018-07-22 12:31:56] WARNING[23884][C-00000029] app_macro.c: No such context 'macro-from-dahdi-1' for macro 'from-dahdi-1'. Was called by s@from-analog
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@from-analog:13] NoOp("DAHDI/1-1", "Returned from Macro from-dahdi-1") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@from-analog:14] Goto("DAHDI/1-1", "from-pstn,s,1") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Goto (from-pstn,s,1)
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@from-pstn:1] Set("DAHDI/1-1", "__DIRECTION=INBOUND") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@from-pstn:2] Gosub("DAHDI/1-1", "sub-record-check,s,1(in,s,dontcare)") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@sub-record-check:1] GotoIf("DAHDI/1-1", "0?initialized") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@sub-record-check:2] Set("DAHDI/1-1", "__REC_STATUS=INITIALIZED") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@sub-record-check:3] Set("DAHDI/1-1", "NOW=1532277116") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@sub-record-check:4] Set("DAHDI/1-1", "__DAY=22") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@sub-record-check:5] Set("DAHDI/1-1", "__MONTH=07") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@sub-record-check:6] Set("DAHDI/1-1", "__YEAR=2018") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@sub-record-check:7] Set("DAHDI/1-1", "__TIMESTR=20180722-123156") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@sub-record-check:8] Set("DAHDI/1-1", "__FROMEXTEN=unknown") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@sub-record-check:9] Set("DAHDI/1-1", "__MON_FMT=wav") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@sub-record-check:10] NoOp("DAHDI/1-1", "Recordings initialized") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@sub-record-check:11] ExecIf("DAHDI/1-1", "0?Set(ARG3=dontcare)") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@sub-record-check:12] Set("DAHDI/1-1", "REC_POLICY_MODE_SAVE=") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@sub-record-check:13] ExecIf("DAHDI/1-1", "0?Set(REC_STATUS=NO)") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@sub-record-check:14] GotoIf("DAHDI/1-1", "2?checkaction") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Goto (sub-record-check,s,17)
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@sub-record-check:17] GotoIf("DAHDI/1-1", "1?sub-record-check,in,1") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Goto (sub-record-check,in,1)
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [in@sub-record-check:1] NoOp("DAHDI/1-1", "Inbound Recording Check to s") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [in@sub-record-check:2] Set("DAHDI/1-1", "FROMEXTEN=unknown") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [in@sub-record-check:3] ExecIf("DAHDI/1-1", "0?Set(FROMEXTEN=)") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [in@sub-record-check:4] Gosub("DAHDI/1-1", "recordcheck,1(dontcare,in,s)") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [recordcheck@sub-record-check:1] NoOp("DAHDI/1-1", "Starting recording check against dontcare") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [recordcheck@sub-record-check:2] Goto("DAHDI/1-1", "dontcare") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Goto (sub-record-check,recordcheck,3)
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [recordcheck@sub-record-check:3] Return("DAHDI/1-1", "") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [in@sub-record-check:5] Return("DAHDI/1-1", "") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@from-pstn:3] Gosub("DAHDI/1-1", "app-blacklist-check,s,1()") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@app-blacklist-check:1] GotoIf("DAHDI/1-1", "0?blacklisted") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@app-blacklist-check:2] Set("DAHDI/1-1", "CALLED_BLACKLIST=1") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@app-blacklist-check:3] Return("DAHDI/1-1", "") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@from-pstn:4] ExecIf("DAHDI/1-1", "1?Set(__FROM_DID=s)") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@from-pstn:5] Set("DAHDI/1-1", "CDR(did)=s") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@from-pstn:6] ExecIf("DAHDI/1-1", "1 ?Set(CALLERID(name)=)") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@from-pstn:7] Set("DAHDI/1-1", "CHANNEL(musicclass)=default") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@from-pstn:8] Set("DAHDI/1-1", "__MOHCLASS=default") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@from-pstn:9] Ringing("DAHDI/1-1", "") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@from-pstn:10] Set("DAHDI/1-1", "__RINGINGSENT=TRUE") in new stack
[2018-07-22 12:31:56] WARNING[23884][C-00000029] func_channel.c: Unknown or unavailable item requested: 'reversecharge'
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@from-pstn:11] GotoIf("DAHDI/1-1", "0?macro-hangupcall") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@from-pstn:12] Set("DAHDI/1-1", "__CALLINGPRES_SV=allowed_not_screened") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@from-pstn:13] Set("DAHDI/1-1", "CALLERPRES()=allowed_not_screened") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@from-pstn:14] Goto("DAHDI/1-1", "ext-group,1,1") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Goto (ext-group,1,1)
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [1@ext-group:1] Macro("DAHDI/1-1", "user-callerid,") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@macro-user-callerid:1] Set("DAHDI/1-1", "TOUCH_MONITOR=1532277106.52") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@macro-user-callerid:2] Set("DAHDI/1-1", "AMPUSER=") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@macro-user-callerid:3] GotoIf("DAHDI/1-1", "0?report") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@macro-user-callerid:4] ExecIf("DAHDI/1-1", "1?Set(REALCALLERIDNUM=)") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@macro-user-callerid:5] Set("DAHDI/1-1", "AMPUSER=") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@macro-user-callerid:6] GotoIf("DAHDI/1-1", "0?limit") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@macro-user-callerid:7] Set("DAHDI/1-1", "AMPUSERCIDNAME=") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@macro-user-callerid:8] GotoIf("DAHDI/1-1", "1?report") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Goto (macro-user-callerid,s,16)
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@macro-user-callerid:16] GotoIf("DAHDI/1-1", "0?continue") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@macro-user-callerid:17] ExecIf("DAHDI/1-1", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@macro-user-callerid:18] Set("DAHDI/1-1", "__TTL=64") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@macro-user-callerid:19] GotoIf("DAHDI/1-1", "1?continue") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Goto (macro-user-callerid,s,30)
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@macro-user-callerid:30] Set("DAHDI/1-1", "CALLERID(number)=") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@macro-user-callerid:31] Set("DAHDI/1-1", "CALLERID(name)=") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@macro-user-callerid:32] Set("DAHDI/1-1", "CDR(cnum)=") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@macro-user-callerid:33] Set("DAHDI/1-1", "CDR(cnam)=") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@macro-user-callerid:34] Set("DAHDI/1-1", "CHANNEL(language)=en") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [1@ext-group:2] Macro("DAHDI/1-1", "blkvm-setifempty,") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@macro-blkvm-setifempty:1] GotoIf("DAHDI/1-1", "1?init") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Goto (macro-blkvm-setifempty,s,4)
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@macro-blkvm-setifempty:4] Set("DAHDI/1-1", "__BLKVM_CHANNEL=DAHDI/1-1") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@macro-blkvm-setifempty:5] Set("DAHDI/1-1", "SHARED(BLKVM,DAHDI/1-1)=TRUE") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@macro-blkvm-setifempty:6] Set("DAHDI/1-1", "GOSUB_RETVAL=TRUE") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@macro-blkvm-setifempty:7] MacroExit("DAHDI/1-1", "") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [1@ext-group:3] GotoIf("DAHDI/1-1", "1?skipov") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Goto (ext-group,1,6)
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [1@ext-group:6] Set("DAHDI/1-1", "RRNODEST=") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [1@ext-group:7] Set("DAHDI/1-1", "__NODEST=1") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [1@ext-group:8] GosubIf("DAHDI/1-1", "0?sub-rgsetcid,s,1()") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [1@ext-group:9] Set("DAHDI/1-1", "__CWIGNORE=TRUE") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [1@ext-group:10] Gosub("DAHDI/1-1", "sub-record-check,s,1(rg,1,dontcare)") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@sub-record-check:1] GotoIf("DAHDI/1-1", "7?initialized") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Goto (sub-record-check,s,10)
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@sub-record-check:10] NoOp("DAHDI/1-1", "Recordings initialized") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@sub-record-check:11] ExecIf("DAHDI/1-1", "0?Set(ARG3=dontcare)") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@sub-record-check:12] Set("DAHDI/1-1", "REC_POLICY_MODE_SAVE=") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@sub-record-check:13] ExecIf("DAHDI/1-1", "0?Set(REC_STATUS=NO)") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@sub-record-check:14] GotoIf("DAHDI/1-1", "2?checkaction") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Goto (sub-record-check,s,17)
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@sub-record-check:17] GotoIf("DAHDI/1-1", "0?sub-record-check,rg,1") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@sub-record-check:18] NoOp("DAHDI/1-1", "Generic rg Recording Check - unknown 1") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@sub-record-check:19] Gosub("DAHDI/1-1", "recordcheck,1(dontcare,rg,1)") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [recordcheck@sub-record-check:1] NoOp("DAHDI/1-1", "Starting recording check against dontcare") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [recordcheck@sub-record-check:2] Goto("DAHDI/1-1", "dontcare") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Goto (sub-record-check,recordcheck,3)
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [recordcheck@sub-record-check:3] Return("DAHDI/1-1", "") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@sub-record-check:20] Return("DAHDI/1-1", "") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [1@ext-group:11] Set("DAHDI/1-1", "RingGroupMethod=ringall") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [1@ext-group:12] Macro("DAHDI/1-1", "dial,20,Ttr,4-5") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@macro-dial:1] GotoIf("DAHDI/1-1", "0?dial") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@macro-dial:2] Set("DAHDI/1-1", "CHANNEL(musicclass)=default") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@macro-dial:3] AGI("DAHDI/1-1", "dialparties.agi") in new stack
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] res_agi.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] res_agi.c: dialparties.agi: Starting New Dialparties.agi
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] res_agi.c: dialparties.agi: Caller ID name is 'unknown' number is 'unknown'
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] res_agi.c: dialparties.agi: Methodology of ring is 'ringall'
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] res_agi.c: -- dialparties.agi: Added extension 4 to extension map
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] res_agi.c: -- dialparties.agi: Added extension 5 to extension map
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] res_agi.c: -- dialparties.agi: Extension 4 cf is disabled
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] res_agi.c: -- dialparties.agi: Extension 5 cf is disabled
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] res_agi.c: -- dialparties.agi: Extension 4 do not disturb is disabled
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] res_agi.c: -- dialparties.agi: Extension 5 do not disturb is disabled
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] res_agi.c: dialparties.agi: EXTENSION_STATE: 4 (UNAVAILABLE)
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] res_agi.c: dialparties.agi: Extension 4 has ExtensionState: 4
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] res_agi.c: -- dialparties.agi: Checking CW and CFB status for extension 4
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] res_agi.c: -- dialparties.agi: DbDel CALLTRACE/4 - Caller ID is not defined
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] res_agi.c: dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE)
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] res_agi.c: dialparties.agi: Extension 5 has ExtensionState: 0
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] res_agi.c: -- dialparties.agi: Checking CW and CFB status for extension 5
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] res_agi.c: -- dialparties.agi: DbDel CALLTRACE/5 - Caller ID is not defined
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] res_agi.c: -- dialparties.agi: Filtered ARG3: 4-5
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] res_agi.c: -- <DAHDI/1-1>AGI Script dialparties.agi completed, returning 0
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@macro-dial:7] Dial("DAHDI/1-1", "SIP/4&SIP/5,20,trM(auto-blkvm)") in new stack
[2018-07-22 12:31:56] WARNING[23884][C-00000029] app_dial.c: Unable to create channel of type 'SIP' (cause 20 - Subscriber absent)
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] netsock2.c: == Using SIP RTP TOS bits 184
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] netsock2.c: == Using SIP RTP CoS mark 5
[2018-07-22 12:31:56] VERBOSE[23884][C-00000029] app_dial.c: -- Called SIP/5
[2018-07-22 12:32:01] WARNING[2401] chan_sip.c: Timeout on [email protected]:5060 on non-critical invite transaction.
[2018-07-22 12:32:14] VERBOSE[23884][C-00000029] app_dial.c: -- SIP/5-00000016 is ringing
[2018-07-22 12:32:16] VERBOSE[23884][C-00000029] app_dial.c: -- Nobody picked up in 20000 ms
[2018-07-22 12:32:16] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@macro-dial:8] Set("DAHDI/1-1", "DIALSTATUS=NOANSWER") in new stack
[2018-07-22 12:32:16] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@macro-dial:9] GosubIf("DAHDI/1-1", "0?NOANSWER,1") in new stack
[2018-07-22 12:32:16] VERBOSE[23884][C-00000029] pbx.c: -- Executing [1@ext-group:13] Gosub("DAHDI/1-1", "sub-record-cancel,s,1()") in new stack
[2018-07-22 12:32:16] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@sub-record-cancel:1] Return("DAHDI/1-1", "") in new stack
[2018-07-22 12:32:16] VERBOSE[23884][C-00000029] pbx.c: -- Executing [1@ext-group:14] Set("DAHDI/1-1", "RingGroupMethod=") in new stack
[2018-07-22 12:32:16] VERBOSE[23884][C-00000029] pbx.c: -- Executing [1@ext-group:15] GotoIf("DAHDI/1-1", "0?nodest") in new stack
[2018-07-22 12:32:16] VERBOSE[23884][C-00000029] pbx.c: -- Executing [1@ext-group:16] Set("DAHDI/1-1", "__CWIGNORE=") in new stack
[2018-07-22 12:32:16] VERBOSE[23884][C-00000029] pbx.c: -- Executing [1@ext-group:17] Set("DAHDI/1-1", "__NODEST=") in new stack
[2018-07-22 12:32:16] VERBOSE[23884][C-00000029] pbx.c: -- Executing [1@ext-group:18] Macro("DAHDI/1-1", "blkvm-clr,") in new stack
[2018-07-22 12:32:16] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@macro-blkvm-clr:1] Set("DAHDI/1-1", "SHARED(BLKVM,DAHDI/1-1)=") in new stack
[2018-07-22 12:32:16] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@macro-blkvm-clr:2] Set("DAHDI/1-1", "GOSUB_RETVAL=") in new stack
[2018-07-22 12:32:16] VERBOSE[23884][C-00000029] pbx.c: -- Executing [s@macro-blkvm-clr:3] MacroExit("DAHDI/1-1", "") in new stack
[2018-07-22 12:32:16] VERBOSE[23884][C-00000029] pbx.c: -- Executing [1@ext-group:19] Goto("DAHDI/1-1", "app-blackhole,hangup,1") in new stack
[2018-07-22 12:32:16] VERBOSE[23884][C-00000029] pbx.c: -- Goto (app-blackhole,hangup,1)
[2018-07-22 12:32:16] VERBOSE[23884][C-00000029] pbx.c: -- Executing [hangup@app-blackhole:1] NoOp("DAHDI/1-1", "Blackhole Dest: Hangup") in new stack
[2018-07-22 12:32:16] VERBOSE[23884][C-00000029] pbx.c: -- Executing [hangup@app-blackhole:2] Hangup("DAHDI/1-1", "") in new stack
[2018-07-22 12:32:16] VERBOSE[23884][C-00000029] pbx.c: == Spawn extension (app-blackhole, hangup, 2) exited non-zero on 'DAHDI/1-1'
[2018-07-22 12:32:16] VERBOSE[23884][C-00000029] sig_analog.c: -- Hanging up on 'DAHDI/1-1'
[2018-07-22 12:32:16] VERBOSE[23884][C-00000029] chan_dahdi.c: -- Hungup 'DAHDI/1-1'
[2018-07-22 12:33:23] NOTICE[2401] chan_sip.c: Peer '5' is now UNREACHABLE! Last qualify: 279
[2018-07-22 12:34:29] NOTICE[2401] chan_sip.c: Peer '5' is now Reachable. (151ms / 2000ms)
[2018-07-22 13:43:20] NOTICE[2401] chan_sip.c: Peer '5' is now Lagged. (2303ms / 2000ms)
[2018-07-22 13:43:30] NOTICE[2401] chan_sip.c: Peer '5' is now Reachable. (142ms / 2000ms)
[2018-07-22 14:49:16] NOTICE[2401] chan_sip.c: Peer '5' is now Lagged. (2312ms / 2000ms)
[2018-07-22 14:49:27] NOTICE[2401] chan_sip.c: Peer '5' is now Reachable. (159ms / 2000ms)
[2018-07-22 16:28:19] NOTICE[2401] chan_sip.c: Peer '5' is now UNREACHABLE! Last qualify: 147
[2018-07-22 16:28:29] NOTICE[2401] chan_sip.c: Peer '5' is now Reachable. (141ms / 2000ms)
[2018-07-22 16:30:33] NOTICE[2401] chan_sip.c: Peer '5' is now UNREACHABLE! Last qualify: 146
[2018-07-22 16:30:57] NOTICE[2401] chan_sip.c: Peer '5' is now Reachable. (141ms / 2000ms)
[2018-07-22 18:27:09] NOTICE[2401] chan_sip.c: Peer '5' is now UNREACHABLE! Last qualify: 142
[2018-07-22 18:27:19] NOTICE[2401] chan_sip.c: Peer '5' is now Reachable. (148ms / 2000ms)
[2018-07-22 18:57:00] NOTICE[2401] chan_sip.c: Peer '5' is now UNREACHABLE! Last qualify: 159
[2018-07-22 18:57:10] NOTICE[2401] chan_sip.c: Peer '5' is now Reachable. (160ms / 2000ms)
[2018-07-22 22:02:44] NOTICE[2401] chan_sip.c: Peer '5' is now Lagged. (2299ms / 2000ms)
[2018-07-22 22:02:55] NOTICE[2401] chan_sip.c: Peer '5' is now Reachable. (144ms / 2000ms)
[2018-07-22 22:11:00] NOTICE[2401] chan_sip.c: Peer '5' is now UNREACHABLE! Last qualify: 142
[2018-07-23 01:03:47] VERBOSE[2401] chan_sip.c: -- Registered SIP '5' at 10.0.6.200:5060
[2018-07-23 01:03:47] NOTICE[2401] chan_sip.c: Peer '5' is now Reachable. (252ms / 2000ms)
[2018-07-23 01:04:51] NOTICE[2401] chan_sip.c: Peer '5' is now UNREACHABLE! Last qualify: 252
[2018-07-23 03:01:00] VERBOSE[2401] chan_sip.c: -- Registered SIP '5' at 10.0.6.200:5060
[2018-07-23 03:01:01] NOTICE[2401] chan_sip.c: Peer '5' is now Reachable. (253ms / 2000ms)
[2018-07-23 03:02:05] NOTICE[2401] chan_sip.c: Peer '5' is now UNREACHABLE! Last qualify: 253

system · July 24, 2019, 6:40pm

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