Asterisk (Ver. 1.8.16.0)
Freepbx framework 2.10.1.6
I am trying to setup automated after hours call forwarding. I have set up a ring group (210) with the phonenumber# of the number to call and I can dial this from a handset successfully. I then set up a time group for M-TH and another for Fri. I then set up a Time Condition for M-Th that rings all on match and goes to Time condition Fri if no match. I have a second Time Condition (Fri that Rings All of matched and goes to Ring Group 210 if no match.
At this point, the call ring into the office fine during business hours but incoming calls after hours ring twice for the caller and the then line is silent. After about 30 seconds the call disconnects. I am pasting me log below. If someone can point me in the right direction to get the after hours call to connect, it would be great. Thank you!
Log:
[2013-07-26 14:49:17] VERBOSE[2643] pbx.c: – Executing [h@macro-dial:1] Macro(“SIP/Pbx-00000ff1”, “hangupcall”) in new stack
[2013-07-26 14:49:17] VERBOSE[2643] pbx.c: – Executing [s@macro-hangupcall:1] GotoIf(“SIP/Pbx-00000ff1”, “1?theend”) in new stack
[2013-07-26 14:49:17] VERBOSE[2643] pbx.c: – Goto (macro-hangupcall,s,3)
[2013-07-26 14:49:17] VERBOSE[2643] pbx.c: – Executing [s@macro-hangupcall:3] ExecIf(“SIP/Pbx-00000ff1”, “0?Set(CDR(recordingfile)=)”) in new stack
[2013-07-26 14:49:17] VERBOSE[2643] pbx.c: – Executing [s@macro-hangupcall:4] Hangup(“SIP/Pbx-00000ff1”, “”) in new stack
[2013-07-26 14:49:17] VERBOSE[2643] app_macro.c: == Spawn extension (macro-hangupcall, s, 4) exited non-zero on ‘SIP/Pbx-00000ff1’ in macro ‘hangupcall’
[2013-07-26 14:49:17] VERBOSE[2643] features.c: == Spawn extension (macro-dial, h, 1) exited non-zero on ‘SIP/Pbx-00000ff1’
[2013-07-26 14:49:17] VERBOSE[2643] app_macro.c: == Spawn extension (macro-dial, s, 7) exited non-zero on ‘SIP/Pbx-00000ff1’ in macro ‘dial’
[2013-07-26 14:49:17] VERBOSE[2643] pbx.c: == Spawn extension (ext-group, 210, 11) exited non-zero on ‘SIP/Pbx-00000ff1’
[2013-07-26 14:49:17] VERBOSE[2645] pbx.c: – Executing [h@macro-dialout-trunk:1] Macro(“Local/91800XXXXXXX@from-internal-d06f;2”, “hangupcall,”) in new stack
[2013-07-26 14:49:17] VERBOSE[2645] pbx.c: – Executing [s@macro-hangupcall:1] GotoIf(“Local/91800XXXXXXX@from-internal-d06f;2”, “1?theend”) in new stack
[2013-07-26 14:49:17] VERBOSE[2645] pbx.c: – Goto (macro-hangupcall,s,3)
[2013-07-26 14:49:17] VERBOSE[2645] pbx.c: – Executing [s@macro-hangupcall:3] ExecIf(“Local/91800XXXXXXX@from-internal-d06f;2”, “0?Set(CDR(recordingfile)=)”) in new stack
[2013-07-26 14:49:17] VERBOSE[2645] pbx.c: – Executing [s@macro-hangupcall:4] Hangup(“Local/91800XXXXXXX@from-internal-d06f;2”, “”) in new stack
[2013-07-26 14:49:17] VERBOSE[2645] app_macro.c: == Spawn extension (macro-hangupcall, s, 4) exited non-zero on ‘Local/91800XXXXXXX@from-internal-d06f;2’ in macro ‘hangupcall’
[2013-07-26 14:49:17] VERBOSE[2645] features.c: == Spawn extension (macro-dialout-trunk, h, 1) exited non-zero on ‘Local/91800XXXXXXX@from-internal-d06f;2’
[2013-07-26 14:49:17] VERBOSE[2645] app_macro.c: == Spawn extension (macro-dialout-trunk, s, 22) exited non-zero on ‘Local/91800XXXXXXX@from-internal-d06f;2’ in macro ‘dialout-trunk’
[2013-07-26 14:49:17] VERBOSE[2645] pbx.c: == Spawn extension (from-internal, 91800XXXXXXX, 6) exited non-zero on ‘Local/91800XXXXXXX@from-internal-d06f;2’
[2013-07-26 14:49:24] VERBOSE[3719] chan_sip.c: – Registered SIP ‘104’ at 192.168.0.214:5060
[2013-07-26 14:49:25] NOTICE[3719] chan_sip.c: Peer ‘104’ is now Reachable. (431ms / 2000ms)
[2013-07-26 14:50:56] VERBOSE[3719] netsock2.c: == Using SIP RTP TOS bits 184
[2013-07-26 14:50:56] VERBOSE[3719] netsock2.c: == Using SIP RTP CoS mark 5
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [XXXXXXXXX@from-trunk:1] Set(“SIP/Pbx-00000ff3”, “__FROM_DID=XXXXXXXXX”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [XXXXXXXXX@from-trunk:2] Gosub(“SIP/Pbx-00000ff3”, “app-blacklist-check,s,1()”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [s@app-blacklist-check:1] GotoIf(“SIP/Pbx-00000ff3”, “0?blacklisted”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [s@app-blacklist-check:2] Set(“SIP/Pbx-00000ff3”, “CALLED_BLACKLIST=1”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [s@app-blacklist-check:3] Return(“SIP/Pbx-00000ff3”, “”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [XXXXXXXXX@from-trunk:3] Set(“SIP/Pbx-00000ff3”, “CDR(did)=XXXXXXXXX”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [XXXXXXXXX@from-trunk:4] ExecIf(“SIP/Pbx-00000ff3”, “0 ?Set(CALLERID(name)=+13524062518)”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [XXXXXXXXX@from-trunk:5] Set(“SIP/Pbx-00000ff3”, “CHANNEL(musicclass)=AceonHold”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [XXXXXXXXX@from-trunk:6] Set(“SIP/Pbx-00000ff3”, “__MOHCLASS=AceonHold”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [XXXXXXXXX@from-trunk:7] Set(“SIP/Pbx-00000ff3”, “__CALLINGPRES_SV=allowed_not_screened”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [XXXXXXXXX@from-trunk:8] Set(“SIP/Pbx-00000ff3”, “CALLERPRES()=allowed_not_screened”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [XXXXXXXXX@from-trunk:9] Goto(“SIP/Pbx-00000ff3”, “timeconditions,1,1”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Goto (timeconditions,1,1)
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [1@timeconditions:1] GotoIfTime(“SIP/Pbx-00000ff3”, “08:30-17:15,mon-thu,1-31,jan-dec?truestate”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [1@timeconditions:2] GotoIf(“SIP/Pbx-00000ff3”, “0?truegoto”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [1@timeconditions:3] ExecIf(“SIP/Pbx-00000ff3”, “0?Set(DB(TC/1)=)”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [1@timeconditions:4] GotoIf(“SIP/Pbx-00000ff3”, “1?timeconditions,2,1”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Goto (timeconditions,2,1)
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [2@timeconditions:1] GotoIfTime(“SIP/Pbx-00000ff3”, “08:30-13:45,fri,1-31,jan-dec?truestate”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [2@timeconditions:2] GotoIf(“SIP/Pbx-00000ff3”, “0?truegoto”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [2@timeconditions:3] ExecIf(“SIP/Pbx-00000ff3”, “0?Set(DB(TC/2)=)”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [2@timeconditions:4] GotoIf(“SIP/Pbx-00000ff3”, “1?ext-group,210,1”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Goto (ext-group,210,1)
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [210@ext-group:1] Macro(“SIP/Pbx-00000ff3”, “user-callerid,”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [s@macro-user-callerid:1] Set(“SIP/Pbx-00000ff3”, “AMPUSER=+13524062518”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [s@macro-user-callerid:2] GotoIf(“SIP/Pbx-00000ff3”, “0?report”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [s@macro-user-callerid:3] ExecIf(“SIP/Pbx-00000ff3”, “1?Set(REALCALLERIDNUM=+13524062518)”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [s@macro-user-callerid:4] Set(“SIP/Pbx-00000ff3”, “AMPUSER=”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [s@macro-user-callerid:5] Set(“SIP/Pbx-00000ff3”, “AMPUSERCIDNAME=”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [s@macro-user-callerid:6] GotoIf(“SIP/Pbx-00000ff3”, “1?report”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Goto (macro-user-callerid,s,13)
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [s@macro-user-callerid:13] GotoIf(“SIP/Pbx-00000ff3”, “0?continue”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [s@macro-user-callerid:14] Set(“SIP/Pbx-00000ff3”, “__TTL=64”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [s@macro-user-callerid:15] GotoIf(“SIP/Pbx-00000ff3”, “1?continue”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Goto (macro-user-callerid,s,26)
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [s@macro-user-callerid:26] Set(“SIP/Pbx-00000ff3”, “CALLERID(number)=+13524062518”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [s@macro-user-callerid:27] Set(“SIP/Pbx-00000ff3”, “CALLERID(name)=BERYL STOKES”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [s@macro-user-callerid:28] Set(“SIP/Pbx-00000ff3”, “CHANNEL(language)=en”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [210@ext-group:2] Macro(“SIP/Pbx-00000ff3”, “blkvm-setifempty,”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [s@macro-blkvm-setifempty:1] GotoIf(“SIP/Pbx-00000ff3”, “1?init”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Goto (macro-blkvm-setifempty,s,4)
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [s@macro-blkvm-setifempty:4] Set(“SIP/Pbx-00000ff3”, “__BLKVM_CHANNEL=SIP/Pbx-00000ff3”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [s@macro-blkvm-setifempty:5] Set(“SIP/Pbx-00000ff3”, “SHARED(BLKVM,SIP/Pbx-00000ff3)=TRUE”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [s@macro-blkvm-setifempty:6] Set(“SIP/Pbx-00000ff3”, “GOSUB_RETVAL=TRUE”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [s@macro-blkvm-setifempty:7] MacroExit(“SIP/Pbx-00000ff3”, “”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [210@ext-group:3] GotoIf(“SIP/Pbx-00000ff3”, “1?skipov”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Goto (ext-group,210,6)
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [210@ext-group:6] Set(“SIP/Pbx-00000ff3”, “RRNODEST=”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [210@ext-group:7] Set(“SIP/Pbx-00000ff3”, “__NODEST=210”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [210@ext-group:8] GosubIf(“SIP/Pbx-00000ff3”, “0?sub-rgsetcid,s,1()”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [210@ext-group:9] Gosub(“SIP/Pbx-00000ff3”, “sub-record-check,s,1(rg,210,dontcare)”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [s@sub-record-check:1] GotoIf(“SIP/Pbx-00000ff3”, “1?check”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Goto (sub-record-check,s,6)
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [s@sub-record-check:6] Set(“SIP/Pbx-00000ff3”, “__MON_FMT=wav”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [s@sub-record-check:7] GotoIf(“SIP/Pbx-00000ff3”, “1?next”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Goto (sub-record-check,s,10)
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [s@sub-record-check:10] ExecIf(“SIP/Pbx-00000ff3”, “0?Return()”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [s@sub-record-check:11] GotoIf(“SIP/Pbx-00000ff3”, “0?rg,1”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [s@sub-record-check:12] Set(“SIP/Pbx-00000ff3”, “__REC_STATUS=INITIALIZED”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [s@sub-record-check:13] ExecIf(“SIP/Pbx-00000ff3”, “1?Set(__REC_POLICY_MODE=dontcare)”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [s@sub-record-check:14] Set(“SIP/Pbx-00000ff3”, “NOW=1374864656”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [s@sub-record-check:15] Set(“SIP/Pbx-00000ff3”, “__DAY=26”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [s@sub-record-check:16] Set(“SIP/Pbx-00000ff3”, “__MONTH=07”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [s@sub-record-check:17] Set(“SIP/Pbx-00000ff3”, “__YEAR=2013”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [s@sub-record-check:18] Set(“SIP/Pbx-00000ff3”, “__TIMESTR=20130726-145056”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [s@sub-record-check:19] Set(“SIP/Pbx-00000ff3”, “__FROMEXTEN=+13524062518”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [s@sub-record-check:20] Set(“SIP/Pbx-00000ff3”, “__CALLFILENAME=rg-210-+13524062518-20130726-145056-1374864656.4177”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [s@sub-record-check:21] Goto(“SIP/Pbx-00000ff3”, “rg,1”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Goto (sub-record-check,rg,1)
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [rg@sub-record-check:1] GosubIf(“SIP/Pbx-00000ff3”, “0?record,1(rg,dontcare,+13524062518)”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [rg@sub-record-check:2] Return(“SIP/Pbx-00000ff3”, “”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [210@ext-group:10] Set(“SIP/Pbx-00000ff3”, “RingGroupMethod=ringall”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [210@ext-group:11] Macro(“SIP/Pbx-00000ff3”, “dial,10,tr,91800XXXXXXX#”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [s@macro-dial:1] GotoIf(“SIP/Pbx-00000ff3”, “0?dial”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [s@macro-dial:2] SetMusicOnHold(“SIP/Pbx-00000ff3”, “AceonHold”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [s@macro-dial:3] AGI(“SIP/Pbx-00000ff3”, “dialparties.agi”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] res_agi.c: – Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
[2013-07-26 14:50:56] VERBOSE[2667] res_agi.c: dialparties.agi: Starting New Dialparties.agi
[2013-07-26 14:50:56] VERBOSE[2667] res_agi.c: dialparties.agi: Caller ID name is ‘BERYL STOKES’ number is ‘+13524062518’
[2013-07-26 14:50:56] VERBOSE[2667] res_agi.c: dialparties.agi: Methodology of ring is ‘ringall’
[2013-07-26 14:50:56] VERBOSE[2667] res_agi.c: – dialparties.agi: Added extension 91800XXXXXXX# to extension map
[2013-07-26 14:50:56] VERBOSE[2667] res_agi.c: – dialparties.agi: Extension 91800XXXXXXX# cf is disabled
[2013-07-26 14:50:56] VERBOSE[2667] res_agi.c: – dialparties.agi: Filtered ARG3: 91800XXXXXXX
[2013-07-26 14:50:56] VERBOSE[2667] res_agi.c: – <SIP/Pbx-00000ff3>AGI Script dialparties.agi completed, returning 0
[2013-07-26 14:50:56] VERBOSE[2667] pbx.c: – Executing [s@macro-dial:7] Dial(“SIP/Pbx-00000ff3”, “Local/91800XXXXXXX@from-internal/n,10,trM(auto-blkvm)”) in new stack
[2013-07-26 14:50:56] VERBOSE[2667] app_dial.c: – Called Local/91800XXXXXXX@from-internal/n
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Executing [91800XXXXXXX@from-internal:1] Macro(“Local/91800XXXXXXX@from-internal-cca5;2”, “user-callerid,LIMIT,”) in new stack
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Executing [s@macro-user-callerid:1] Set(“Local/91800XXXXXXX@from-internal-cca5;2”, “AMPUSER=+13524062518”) in new stack
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Executing [s@macro-user-callerid:2] GotoIf(“Local/91800XXXXXXX@from-internal-cca5;2”, “0?report”) in new stack
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Executing [s@macro-user-callerid:3] ExecIf(“Local/91800XXXXXXX@from-internal-cca5;2”, “1?Set(REALCALLERIDNUM=+13524062518)”) in new stack
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Executing [s@macro-user-callerid:4] Set(“Local/91800XXXXXXX@from-internal-cca5;2”, “AMPUSER=”) in new stack
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Executing [s@macro-user-callerid:5] Set(“Local/91800XXXXXXX@from-internal-cca5;2”, “AMPUSERCIDNAME=”) in new stack
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Executing [s@macro-user-callerid:6] GotoIf(“Local/91800XXXXXXX@from-internal-cca5;2”, “1?report”) in new stack
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Goto (macro-user-callerid,s,13)
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Executing [s@macro-user-callerid:13] GotoIf(“Local/91800XXXXXXX@from-internal-cca5;2”, “1?continue”) in new stack
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Goto (macro-user-callerid,s,26)
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Executing [s@macro-user-callerid:26] Set(“Local/91800XXXXXXX@from-internal-cca5;2”, “CALLERID(number)=+13524062518”) in new stack
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Executing [s@macro-user-callerid:27] Set(“Local/91800XXXXXXX@from-internal-cca5;2”, “CALLERID(name)=BERYL STOKES”) in new stack
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Executing [s@macro-user-callerid:28] Set(“Local/91800XXXXXXX@from-internal-cca5;2”, “CHANNEL(language)=en”) in new stack
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Executing [91800XXXXXXX@from-internal:2] Set(“Local/91800XXXXXXX@from-internal-cca5;2”, “MOHCLASS=AceonHold”) in new stack
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Executing [91800XXXXXXX@from-internal:3] ExecIf(“Local/91800XXXXXXX@from-internal-cca5;2”, “0?Set(TRUNKCIDOVERRIDE=XXXXXXXXX)”) in new stack
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Executing [91800XXXXXXX@from-internal:4] Set(“Local/91800XXXXXXX@from-internal-cca5;2”, “_NODEST=”) in new stack
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Executing [91800XXXXXXX@from-internal:5] Gosub(“Local/91800XXXXXXX@from-internal-cca5;2”, “sub-record-check,s,1(out,91800XXXXXXX,)”) in new stack
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Executing [s@sub-record-check:1] GotoIf(“Local/91800XXXXXXX@from-internal-cca5;2”, “1?check”) in new stack
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Goto (sub-record-check,s,6)
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Executing [s@sub-record-check:6] Set(“Local/91800XXXXXXX@from-internal-cca5;2”, “__MON_FMT=wav”) in new stack
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Executing [s@sub-record-check:7] GotoIf(“Local/91800XXXXXXX@from-internal-cca5;2”, “1?next”) in new stack
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Goto (sub-record-check,s,10)
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Executing [s@sub-record-check:10] ExecIf(“Local/91800XXXXXXX@from-internal-cca5;2”, “0?Return()”) in new stack
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Executing [s@sub-record-check:11] GotoIf(“Local/91800XXXXXXX@from-internal-cca5;2”, “1?out,1”) in new stack
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Goto (sub-record-check,out,1)
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Executing [out@sub-record-check:1] ExecIf(“Local/91800XXXXXXX@from-internal-cca5;2”, “0?Set(__REC_POLICY_MODE=)”) in new stack
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Executing [out@sub-record-check:2] GosubIf(“Local/91800XXXXXXX@from-internal-cca5;2”, “0?record,1(exten,91800XXXXXXX,+13524062518)”) in new stack
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Executing [out@sub-record-check:3] Return(“Local/91800XXXXXXX@from-internal-cca5;2”, “”) in new stack
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Executing [91800XXXXXXX@from-internal:6] Macro(“Local/91800XXXXXXX@from-internal-cca5;2”, “dialout-trunk,2,18003356500,”) in new stack
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Executing [s@macro-dialout-trunk:1] Set(“Local/91800XXXXXXX@from-internal-cca5;2”, “DIAL_TRUNK=2”) in new stack
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Executing [s@macro-dialout-trunk:2] GosubIf(“Local/91800XXXXXXX@from-internal-cca5;2”, “0?sub-pincheck,s,1()”) in new stack
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Executing [s@macro-dialout-trunk:3] GotoIf(“Local/91800XXXXXXX@from-internal-cca5;2”, “0?disabletrunk,1”) in new stack
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Executing [s@macro-dialout-trunk:4] Set(“Local/91800XXXXXXX@from-internal-cca5;2”, “DIAL_NUMBER=18003356500”) in new stack
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Executing [s@macro-dialout-trunk:5] Set(“Local/91800XXXXXXX@from-internal-cca5;2”, “DIAL_TRUNK_OPTIONS=tr”) in new stack
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Executing [s@macro-dialout-trunk:6] Set(“Local/91800XXXXXXX@from-internal-cca5;2”, “OUTBOUND_GROUP=OUT_2”) in new stack
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Executing [s@macro-dialout-trunk:7] GotoIf(“Local/91800XXXXXXX@from-internal-cca5;2”, “1?nomax”) in new stack
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Goto (macro-dialout-trunk,s,9)
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Executing [s@macro-dialout-trunk:9] GotoIf(“Local/91800XXXXXXX@from-internal-cca5;2”, “0?skipoutcid”) in new stack
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Executing [s@macro-dialout-trunk:10] Set(“Local/91800XXXXXXX@from-internal-cca5;2”, “DIAL_TRUNK_OPTIONS=”) in new stack
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Executing [s@macro-dialout-trunk:11] Macro(“Local/91800XXXXXXX@from-internal-cca5;2”, “outbound-callerid,2”) in new stack
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Executing [s@macro-outbound-callerid:1] ExecIf(“Local/91800XXXXXXX@from-internal-cca5;2”, “1?Set(CALLERPRES()=allowed_not_screened)”) in new stack
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Executing [s@macro-outbound-callerid:2] ExecIf(“Local/91800XXXXXXX@from-internal-cca5;2”, “0?Set(REALCALLERIDNUM=+13524062518)”) in new stack
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Executing [s@macro-outbound-callerid:3] GotoIf(“Local/91800XXXXXXX@from-internal-cca5;2”, “0?normcid”) in new stack
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Executing [s@macro-outbound-callerid:4] Set(“Local/91800XXXXXXX@from-internal-cca5;2”, “USEROUTCID=+13524062518”) in new stack
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Executing [s@macro-outbound-callerid:5] GotoIf(“Local/91800XXXXXXX@from-internal-cca5;2”, “1?bypass”) in new stack
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Goto (macro-outbound-callerid,s,7)
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Executing [s@macro-outbound-callerid:7] Set(“Local/91800XXXXXXX@from-internal-cca5;2”, “EMERGENCYCID=”) in new stack
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Executing [s@macro-outbound-callerid:8] Set(“Local/91800XXXXXXX@from-internal-cca5;2”, “TRUNKOUTCID=3527284779”) in new stack
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Executing [s@macro-outbound-callerid:9] GotoIf(“Local/91800XXXXXXX@from-internal-cca5;2”, “1?trunkcid”) in new stack
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Goto (macro-outbound-callerid,s,12)
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Executing [s@macro-outbound-callerid:12] ExecIf(“Local/91800XXXXXXX@from-internal-cca5;2”, “1?Set(CALLERID(all)=3527284779)”) in new stack
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Executing [s@macro-outbound-callerid:13] ExecIf(“Local/91800XXXXXXX@from-internal-cca5;2”, “1?Set(CALLERID(all)=+13524062518)”) in new stack
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Executing [s@macro-outbound-callerid:14] ExecIf(“Local/91800XXXXXXX@from-internal-cca5;2”, “0?Set(CALLERID(all)=)”) in new stack
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Executing [s@macro-outbound-callerid:15] ExecIf(“Local/91800XXXXXXX@from-internal-cca5;2”, “0?Set(CALLERPRES()=prohib_passed_screen)”) in new stack
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Executing [s@macro-dialout-trunk:12] GosubIf(“Local/91800XXXXXXX@from-internal-cca5;2”, “0?sub-flp-2,s,1()”) in new stack
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Executing [s@macro-dialout-trunk:13] Set(“Local/91800XXXXXXX@from-internal-cca5;2”, “OUTNUM=18003356500”) in new stack
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Executing [s@macro-dialout-trunk:14] Set(“Local/91800XXXXXXX@from-internal-cca5;2”, “custom=SIP/Pbx”) in new stack
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Executing [s@macro-dialout-trunk:15] ExecIf(“Local/91800XXXXXXX@from-internal-cca5;2”, “1?Set(DIAL_TRUNK_OPTIONS=M(setmusic^AceonHold))”) in new stack
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Executing [s@macro-dialout-trunk:16] ExecIf(“Local/91800XXXXXXX@from-internal-cca5;2”, “0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^AceonHold)M(confirm))”) in new stack
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Executing [s@macro-dialout-trunk:17] Macro(“Local/91800XXXXXXX@from-internal-cca5;2”, “dialout-trunk-predial-hook,”) in new stack
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit(“Local/91800XXXXXXX@from-internal-cca5;2”, “”) in new stack
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Executing [s@macro-dialout-trunk:18] GotoIf(“Local/91800XXXXXXX@from-internal-cca5;2”, “0?bypass,1”) in new stack
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Executing [s@macro-dialout-trunk:19] ExecIf(“Local/91800XXXXXXX@from-internal-cca5;2”, “0?Set(CONNECTEDLINE(num,i)=18003356500)”) in new stack
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Executing [s@macro-dialout-trunk:20] ExecIf(“Local/91800XXXXXXX@from-internal-cca5;2”, “0?Set(CONNECTEDLINE(name,i)=CID:+13524062518)”) in new stack
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Executing [s@macro-dialout-trunk:21] GotoIf(“Local/91800XXXXXXX@from-internal-cca5;2”, “0?customtrunk”) in new stack
[2013-07-26 14:50:56] VERBOSE[2669] pbx.c: – Executing [s@macro-dialout-trunk:22] Dial(“Local/91800XXXXXXX@from-internal-cca5;2”, “SIP/Pbx/18003356500,300,M(setmusic^AceonHold)”) in new stack
[2013-07-26 14:50:56] VERBOSE[2669] netsock2.c: == Using SIP RTP TOS bits 184
[2013-07-26 14:50:56] VERBOSE[2669] netsock2.c: == Using SIP RTP CoS mark 5
[2013-07-26 14:50:56] VERBOSE[2669] app_dial.c: – Called SIP/Pbx/18003356500
[2013-07-26 14:50:58] VERBOSE[2669] app_dial.c: – SIP/Pbx-00000ff4 is ringing
[2013-07-26 14:50:58] VERBOSE[2667] app_dial.c: – Local/91800XXXXXXX@from-internal-cca5;1 is ringing
[2013-07-26 14:50:58] VERBOSE[2669] app_dial.c: – SIP/Pbx-00000ff4 is making progress passing it to Local/91800XXXXXXX@from-internal-cca5;2
[2013-07-26 14:50:58] VERBOSE[2667] app_dial.c: – Local/91800XXXXXXX@from-internal-cca5;1 is making progress passing it to SIP/Pbx-00000ff3
[2013-07-26 14:51:04] VERBOSE[2669] app_dial.c: – SIP/Pbx-00000ff4 answered Local/91800XXXXXXX@from-internal-cca5;2
[2013-07-26 14:51:04] VERBOSE[2669] pbx.c: – Executing [s@macro-setmusic:1] Set(“SIP/Pbx-00000ff4”, “CHANNEL(musicclass)=AceonHold”) in new stack
[2013-07-26 14:51:04] VERBOSE[2667] app_dial.c: – Local/91800XXXXXXX@from-internal-cca5;1 answered SIP/Pbx-00000ff3
[2013-07-26 14:51:04] VERBOSE[2667] pbx.c: – Executing [s@macro-auto-blkvm:1] Set(“Local/91800XXXXXXX@from-internal-cca5;1”, “__MACRO_RESULT=”) in new stack
[2013-07-26 14:51:04] VERBOSE[2667] pbx.c: – Executing [s@macro-auto-blkvm:2] Macro(“Local/91800XXXXXXX@from-internal-cca5;1”, “blkvm-clr,”) in new stack
[2013-07-26 14:51:04] VERBOSE[2667] pbx.c: – Executing [s@macro-blkvm-clr:1] Set(“Local/91800XXXXXXX@from-internal-cca5;1”, “SHARED(BLKVM,SIP/Pbx-00000ff3)=”) in new stack
[2013-07-26 14:51:04] VERBOSE[2667] pbx.c: – Executing [s@macro-blkvm-clr:2] Set(“Local/91800XXXXXXX@from-internal-cca5;1”, “GOSUB_RETVAL=”) in new stack
[2013-07-26 14:51:04] VERBOSE[2667] pbx.c: – Executing [s@macro-blkvm-clr:3] MacroExit(“Local/91800XXXXXXX@from-internal-cca5;1”, “”) in new stack
[2013-07-26 14:51:04] VERBOSE[2667] pbx.c: – Executing [s@macro-auto-blkvm:3] ExecIf(“Local/91800XXXXXXX@from-internal-cca5;1”, “0?Set(MASTER_CHANNEL(CONNECTEDLINE(num))=91800XXXXXXX@from-internal/n)”) in new stack
[2013-07-26 14:51:04] VERBOSE[2667] pbx.c: – Executing [s@macro-auto-blkvm:4] ExecIf(“Local/91800XXXXXXX@from-internal-cca5;1”, “0?Set(MASTER_CHANNEL(CONNECTEDLINE(name))=)”) in new stack
[2013-07-26 14:51:35] NOTICE[3719] chan_sip.c: Disconnecting call ‘SIP/Pbx-00000ff3’ for lack of RTP activity in 31 seconds
[2013-07-26 14:51:35] NOTICE[3719] chan_sip.c: Disconnecting call ‘SIP/Pbx-00000ff4’ for lack of RTP activity in 31 seconds
[2013-07-26 14:51:35] VERBOSE[2667] pbx.c: – Executing [h@macro-dial:1] Macro(“SIP/Pbx-00000ff3”, “hangupcall”) in new stack
[2013-07-26 14:51:35] VERBOSE[2669] pbx.c: – Executing [h@macro-dialout-trunk:1] Macro(“Local/91800XXXXXXX@from-internal-cca5;2”, “hangupcall,”) in new stack
[2013-07-26 14:51:35] VERBOSE[2667] pbx.c: – Executing [s@macro-hangupcall:1] GotoIf(“SIP/Pbx-00000ff3”, “1?theend”) in new stack
[2013-07-26 14:51:35] VERBOSE[2667] pbx.c: – Goto (macro-hangupcall,s,3)
[2013-07-26 14:51:35] VERBOSE[2669] pbx.c: – Executing [s@macro-hangupcall:1] GotoIf(“Local/91800XXXXXXX@from-internal-cca5;2”, “1?theend”) in new stack
[2013-07-26 14:51:35] VERBOSE[2667] pbx.c: – Executing [s@macro-hangupcall:3] ExecIf(“SIP/Pbx-00000ff3”, “0?Set(CDR(recordingfile)=)”) in new stack
[2013-07-26 14:51:35] VERBOSE[2669] pbx.c: – Goto (macro-hangupcall,s,3)
[2013-07-26 14:51:35] VERBOSE[2667] pbx.c: – Executing [s@macro-hangupcall:4] Hangup(“SIP/Pbx-00000ff3”, “”) in new stack
[2013-07-26 14:51:35] VERBOSE[2667] app_macro.c: == Spawn extension (macro-hangupcall, s, 4) exited non-zero on ‘SIP/Pbx-00000ff3’ in macro ‘hangupcall’
[2013-07-26 14:51:35] VERBOSE[2669] pbx.c: – Executing [s@macro-hangupcall:3] ExecIf(“Local/91800XXXXXXX@from-internal-cca5;2”, “0?Set(CDR(recordingfile)=)”) in new stack
[2013-07-26 14:51:35] VERBOSE[2667] features.c: == Spawn extension (macro-dial, h, 1) exited non-zero on ‘SIP/Pbx-00000ff3’
[2013-07-26 14:51:35] VERBOSE[2669] pbx.c: – Executing [s@macro-hangupcall:4] Hangup(“Local/91800XXXXXXX@from-internal-cca5;2”, “”) in new stack
[2013-07-26 14:51:35] VERBOSE[2669] app_macro.c: == Spawn extension (macro-hangupcall, s, 4) exited non-zero on ‘Local/91800XXXXXXX@from-internal-cca5;2’ in macro ‘hangupcall’
[2013-07-26 14:51:35] VERBOSE[2669] features.c: == Spawn extension (macro-dialout-trunk, h, 1) exited non-zero on ‘Local/91800XXXXXXX@from-internal-cca5;2’
[2013-07-26 14:51:35] VERBOSE[2667] app_macro.c: == Spawn extension (macro-dial, s, 7) exited non-zero on ‘SIP/Pbx-00000ff3’ in macro ‘dial’
[2013-07-26 14:51:35] VERBOSE[2667] pbx.c: == Spawn extension (ext-group, 210, 11) exited non-zero on ‘SIP/Pbx-00000ff3’
[2013-07-26 14:51:35] VERBOSE[2669] app_macro.c: == Spawn extension (macro-dialout-trunk, s, 22) exited non-zero on ‘Local/91800XXXXXXX@from-internal-cca5;2’ in macro ‘dialout-trunk’
[2013-07-26 14:51:35] VERBOSE[2669] pbx.c: == Spawn extension (from-internal, 91800XXXXXXX, 6) exited non-zero on ‘Local/91800XXXXXXX@from-internal-cca5;2’