2nd agent in Queue of 2 agents only rings one time

General Help
1

All, I have a PBXact instance with multiple queues working properly. I have a single queue that has 2 static agents using a linear ring method. The 2nd agent in the queue only rings one time and then rolls back to first agent. I also tried rrordered with the same effect. I am now using ringall until I can find a fix. This successfully rings both agents the defined amount of time.

For troubleshooting, I have swapped agent positions, removed agents, deleted and recreated extensions, deleted and recreated queue, created s new queue with a different queue number. Same effect - Second agent only rings once.

Can someone show me a proper queue config file or point me in the right direction? This is a weird issue I don’t fully understand.

Thanks!

2

A log of a failed call might help. You can find it in /var/log/asterisk/full - just extract enough of the log that we see the entire call sequence.

3

Here you go. I will need to break log into 4-5 posts due to length. Thanks for the help! Notice 204 is the first extension, 203 is the second. 203 is the one that only rings one time before attempting 204 again, then to vmail.

4

[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx_builtins.c: Goto (ext-queues,102,1)
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:1] Macro(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “user-callerid,”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-user-callerid:1] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “TOUCH_MONITOR=1551117592.541446”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-user-callerid:2] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “AMPUSER=OBFUSCATED”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-user-callerid:3] GotoIf(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “0?report”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-user-callerid:4] ExecIf(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “1?Set(REALCALLERIDNUM=OBFUSCATED)”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-user-callerid:5] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “AMPUSER=”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-user-callerid:6] GotoIf(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “0?limit”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-user-callerid:7] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “AMPUSERCIDNAME=”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-user-callerid:8] ExecIf(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “0?Set(__CIDMASQUERADING=TRUE)”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-user-callerid:9] GotoIf(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “1?report”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx_builtins.c: Goto (macro-user-callerid,s,16)
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-user-callerid:16] NoOp(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “Macro Depth is 1”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-user-callerid:17] GotoIf(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “1?report2:macroerror”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx_builtins.c: Goto (macro-user-callerid,s,18)
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-user-callerid:18] GotoIf(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “0?continue”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-user-callerid:19] ExecIf(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “1?Set(__CALLEE_ACCOUNCODE=)”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-user-callerid:20] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “__TTL=64”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-user-callerid:21] GotoIf(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “1?continue”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx_builtins.c: Goto (macro-user-callerid,s,37)
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-user-callerid:37] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “CALLERID(number)=OBFUSCATED”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-user-callerid:38] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “CALLERID(name)=OBFUSCATED”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-user-callerid:39] GotoIf(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “0?cnum”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-user-callerid:40] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “CDR(cnam)=OBFUSCATED”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-user-callerid:41] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “CDR(cnum)=OBFUSCATED”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-user-callerid:42] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “CHANNEL(language)=en”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-user-callerid:43] ExecIf(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “0?Set(CALLERID(name)=)”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-user-callerid:44] GosubIf(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “0?app-check-classofservce,s,1()”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:2] Answer(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:3] Macro(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “blkvm-set,reset”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-blkvm-set:1] ExecIf(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “1?Set(__BLKVM_CHANNEL=SIP/fpbx-1-wW28Oy1XkZCv-000120ec)”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-blkvm-set:2] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “SHARED(BLKVM,SIP/fpbx-1-wW28Oy1XkZCv-000120ec)=TRUE”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-blkvm-set:3] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “GOSUB_RETVAL=TRUE”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-blkvm-set:4] MacroExit(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:4] ExecIf(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “1?Set(_DIAL_OPTIONS=HhTtrM(auto-blkvm))”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:5] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “__NODEST=102”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:6] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “QCIDPP=”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:7] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “VQ_CIDPP=”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:8] ExecIf(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “0?Macro(prepend-cid,)”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:9] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “QAINFO=”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:10] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “VQ_AINFO=”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:11] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “__RVOL_MODE=dontcare”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:12] ExecIf(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “0?Set(__ALERT_INFO=)”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:13] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “QJOINMSG=”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:14] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “VQ_JOINMSG=”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:15] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “QCANCELMISSED=”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:16] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “QRETRY=”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:17] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “VQ_RETRY=”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:18] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “QOPTIONS=t”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:19] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “VQ_OPTIONS=”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:20] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “QGOSUB=”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:21] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “VQ_GOSUB=”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:22] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “QAGI=”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:23] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “VQ_AGI=”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:24] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “QRULE=0”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:25] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “QRULE=0”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:26] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “VQ_RULE=”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:27] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “QPOSITION=”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:28] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “VQ_POSITION=”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:29] Gosub(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “sub-record-check,s,1(q,102,dontcare)”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@sub-record-check:1] GotoIf(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “11?initialized”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx_builtins.c: Goto (sub-record-check,s,10)
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@sub-record-check:10] NoOp(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “Recordings initialized”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@sub-record-check:11] ExecIf(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “0?Set(ARG3=dontcare)”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@sub-record-check:12] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “REC_POLICY_MODE_SAVE=”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@sub-record-check:13] ExecIf(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “0?Set(REC_STATUS=NO)”) in new stack
[2019-02-25 10:00:25] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@sub-record-check:14] GotoIf(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “1?checkaction”) in new stack
[2019-02-25 10:00:26] VERBOSE[27209][C-0001651d] pbx_builtins.c: Goto (sub-record-check,s,17)
[2019-02-25 10:00:26] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@sub-record-check:17] GotoIf(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “0?sub-record-check,q,1”) in new stack
[2019-02-25 10:00:26] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@sub-record-check:18] NoOp(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “Generic q Recording Check - OBFUSCATED 102”) in new stack
[2019-02-25 10:00:26] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@sub-record-check:19] Gosub(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “recordcheck,1(dontcare,q,102)”) in new stack
[2019-02-25 10:00:26] VERBOSE[27209][C-0001651d] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “Starting recording check against dontcare”) in new stack
[2019-02-25 10:00:26] VERBOSE[27209][C-0001651d] pbx.c: Executing [recordcheck@sub-record-check:2] Goto(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “dontcare”) in new stack
[2019-02-25 10:00:26] VERBOSE[27209][C-0001651d] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2019-02-25 10:00:26] VERBOSE[27209][C-0001651d] pbx.c: Executing [recordcheck@sub-record-check:3] Return(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “”) in new stack
[2019-02-25 10:00:26] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@sub-record-check:20] Return(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “”) in new stack
[2019-02-25 10:00:26] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:30] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “__CWIGNORE=TRUE”) in new stack
[2019-02-25 10:00:26] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:31] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “__SIGNORE=TRUE”) in new stack
[2019-02-25 10:00:26] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:32] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “__QC_CONFIRM=0”) in new stack
[2019-02-25 10:00:26] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:33] GotoIf(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “0?QVQANNOUNCE:NOQVQANNOUNCE”) in new stack
[2019-02-25 10:00:26] VERBOSE[27209][C-0001651d] pbx_builtins.c: Goto (ext-queues,102,38)
[2019-02-25 10:00:26] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:38] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “VQ_CONFIRMMSG=”) in new stack
[2019-02-25 10:00:26] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:39] ExecIf(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “0?Playback(, )”) in new stack
[2019-02-25 10:00:26] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:40] QueueLog(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “102,1551117592.541446,NONE,DID,5413865070”) in new stack
[2019-02-25 10:00:26] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:41] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “QAANNOUNCE=”) in new stack
[2019-02-25 10:00:26] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:42] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “VQ_AANNOUNCE=”) in new stack
[2019-02-25 10:00:26] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:43] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “QMOH=”) in new stack
[2019-02-25 10:00:26] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:44] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “VQ_MOH=”) in new stack
[2019-02-25 10:00:26] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:45] ExecIf(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “0?Set(__MOHCLASS=)”) in new stack
[2019-02-25 10:00:26] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:46] ExecIf(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “0?Set(CHANNEL(musicclass)=)”) in new stack
[2019-02-25 10:00:26] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:47] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “QMAXWAIT=30”) in new stack
[2019-02-25 10:00:26] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:48] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “VQ_MAXWAIT=”) in new stack
[2019-02-25 10:00:26] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:49] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “QUEUENUM=102”) in new stack
[2019-02-25 10:00:26] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:50] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “QUEUEJOINTIME=1551117626”) in new stack
[2019-02-25 10:00:26] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:51] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “QMIN_PENALTY=”) in new stack
[2019-02-25 10:00:26] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:52] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “VQ_MIN_PENALTY=”) in new stack
[2019-02-25 10:00:26] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:53] ExecIf(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “0?Set(QUEUE_MIN_PENALTY=)”) in new stack
[2019-02-25 10:00:26] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:54] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “QMAX_PENALTY=”) in new stack
[2019-02-25 10:00:26] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:55] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “VQ_MAX_PENALTY=”) in new stack
[2019-02-25 10:00:26] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:56] ExecIf(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “0?Set(QUEUE_MAX_PENALTY=)”) in new stack
[2019-02-25 10:00:26] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:57] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “_CALLER_DEST=”) in new stack
[2019-02-25 10:00:26] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:58] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “VQ_CDEST=”) in new stack
[2019-02-25 10:00:26] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:59] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “_AGENT_DEST=”) in new stack
[2019-02-25 10:00:26] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:60] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “VQ_ADEST=”) in new stack
[2019-02-25 10:00:26] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:61] Queue(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “102,t,30,0,”) in new stack
[2019-02-25 10:00:26] VERBOSE[27209][C-0001651d] res_musiconhold.c: Started music on hold, class ‘default’, on channel ‘SIP/fpbx-1-wW28Oy1XkZCv-000120ec’
[2019-02-25 10:00:26] VERBOSE[27209][C-0001651d] app_queue.c: Called Local/204@from-queue/n
[2019-02-25 10:00:26] VERBOSE[27209][C-0001651d] app_queue.c: – LazyMembers debugging - Numbusies: 0, Nummems: 2
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [204@from-queue:1] Set(“Local/204@from-queue-0001bc2c;2”, “QAGENT=204”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [204@from-queue:2] Set(“Local/204@from-queue-0001bc2c;2”, “__FROMQ=true”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [204@from-queue:3] GotoIf(“Local/204@from-queue-0001bc2c;2”, “0?hangup”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [204@from-queue:4] GotoIf(“Local/204@from-queue-0001bc2c;2”, “1?102,1:hangup”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx_builtins.c: Goto (from-queue,102,1)
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [102@from-queue:1] Goto(“Local/204@from-queue-0001bc2c;2”, “from-internal,204,1”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx_builtins.c: Goto (from-internal,204,1)
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [204@from-internal:1] GotoIf(“Local/204@from-queue-0001bc2c;2”, “1?ext-local,204,1:followme-check,204,1”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx_builtins.c: Goto (ext-local,204,1)
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [204@ext-local:1] Set(“Local/204@from-queue-0001bc2c;2”, “__RINGTIMER=15”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [204@ext-local:2] Macro(“Local/204@from-queue-0001bc2c;2”, “exten-vm,204,204,0,0,0”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-exten-vm:1] Macro(“Local/204@from-queue-0001bc2c;2”, “user-callerid,”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-user-callerid:1] Set(“Local/204@from-queue-0001bc2c;2”, “TOUCH_MONITOR=1551117626.541454”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-user-callerid:2] Set(“Local/204@from-queue-0001bc2c;2”, “AMPUSER=OBFUSCATED”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-user-callerid:3] GotoIf(“Local/204@from-queue-0001bc2c;2”, “1?report”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx_builtins.c: Goto (macro-user-callerid,s,16)
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-user-callerid:16] NoOp(“Local/204@from-queue-0001bc2c;2”, “Macro Depth is 2”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-user-callerid:17] GotoIf(“Local/204@from-queue-0001bc2c;2”, “1?report2:macroerror”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx_builtins.c: Goto (macro-user-callerid,s,18)
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-user-callerid:18] GotoIf(“Local/204@from-queue-0001bc2c;2”, “0?continue”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-user-callerid:19] ExecIf(“Local/204@from-queue-0001bc2c;2”, “1?Set(__CALLEE_ACCOUNCODE=)”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-user-callerid:20] Set(“Local/204@from-queue-0001bc2c;2”, “__TTL=63”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-user-callerid:21] GotoIf(“Local/204@from-queue-0001bc2c;2”, “1?continue”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx_builtins.c: Goto (macro-user-callerid,s,37)
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-user-callerid:37] Set(“Local/204@from-queue-0001bc2c;2”, “CALLERID(number)=OBFUSCATED”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-user-callerid:38] Set(“Local/204@from-queue-0001bc2c;2”, “CALLERID(name)=OBFUSCATED”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-user-callerid:39] GotoIf(“Local/204@from-queue-0001bc2c;2”, “0?cnum”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-user-callerid:40] Set(“Local/204@from-queue-0001bc2c;2”, “CDR(cnam)=OBFUSCATED”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-user-callerid:41] Set(“Local/204@from-queue-0001bc2c;2”, “CDR(cnum)=OBFUSCATED”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-user-callerid:42] Set(“Local/204@from-queue-0001bc2c;2”, “CHANNEL(language)=en”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-user-callerid:43] ExecIf(“Local/204@from-queue-0001bc2c;2”, “0?Set(CALLERID(name)=)”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-user-callerid:44] GosubIf(“Local/204@from-queue-0001bc2c;2”, “0?app-check-classofservce,s,1()”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-exten-vm:2] Set(“Local/204@from-queue-0001bc2c;2”, “RingGroupMethod=none”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-exten-vm:3] Set(“Local/204@from-queue-0001bc2c;2”, “__EXTTOCALL=204”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-exten-vm:4] Set(“Local/204@from-queue-0001bc2c;2”, “__PICKUPMARK=204”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-exten-vm:5] Set(“Local/204@from-queue-0001bc2c;2”, “RT=15”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-exten-vm:6] Gosub(“Local/204@from-queue-0001bc2c;2”, “sub-record-check,s,1(exten,204,dontcare)”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@sub-record-check:1] GotoIf(“Local/204@from-queue-0001bc2c;2”, “0?initialized”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@sub-record-check:2] Set(“Local/204@from-queue-0001bc2c;2”, “__REC_STATUS=INITIALIZED”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@sub-record-check:3] Set(“Local/204@from-queue-0001bc2c;2”, “NOW=1551117626”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@sub-record-check:4] Set(“Local/204@from-queue-0001bc2c;2”, “__DAY=25”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@sub-record-check:5] Set(“Local/204@from-queue-0001bc2c;2”, “__MONTH=02”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@sub-record-check:6] Set(“Local/204@from-queue-0001bc2c;2”, “__YEAR=2019”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@sub-record-check:7] Set(“Local/204@from-queue-0001bc2c;2”, “__TIMESTR=20190225-100026”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@sub-record-check:8] Set(“Local/204@from-queue-0001bc2c;2”, “__FROMEXTEN=OBFUSCATED”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@sub-record-check:9] Set(“Local/204@from-queue-0001bc2c;2”, “__MON_FMT=wav”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@sub-record-check:10] NoOp(“Local/204@from-queue-0001bc2c;2”, “Recordings initialized”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@sub-record-check:11] ExecIf(“Local/204@from-queue-0001bc2c;2”, “0?Set(ARG3=dontcare)”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@sub-record-check:12] Set(“Local/204@from-queue-0001bc2c;2”, “REC_POLICY_MODE_SAVE=”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@sub-record-check:13] ExecIf(“Local/204@from-queue-0001bc2c;2”, “0?Set(REC_STATUS=NO)”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@sub-record-check:14] GotoIf(“Local/204@from-queue-0001bc2c;2”, “5?checkaction”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx_builtins.c: Goto (sub-record-check,s,17)
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@sub-record-check:17] GotoIf(“Local/204@from-queue-0001bc2c;2”, “1?sub-record-check,exten,1”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx_builtins.c: Goto (sub-record-check,exten,1)
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [exten@sub-record-check:1] NoOp(“Local/204@from-queue-0001bc2c;2”, “Exten Recording Check between OBFUSCATED and 204”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [exten@sub-record-check:2] Set(“Local/204@from-queue-0001bc2c;2”, “CALLTYPE=external”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [exten@sub-record-check:3] ExecIf(“Local/204@from-queue-0001bc2c;2”, “0?Set(CALLTYPE=)”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [exten@sub-record-check:4] Set(“Local/204@from-queue-0001bc2c;2”, “CALLEE=dontcare”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [exten@sub-record-check:5] ExecIf(“Local/204@from-queue-0001bc2c;2”, “0?Set(CALLEE=dontcare)”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [exten@sub-record-check:6] GotoIf(“Local/204@from-queue-0001bc2c;2”, “1?callee”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx_builtins.c: Goto (sub-record-check,exten,11)
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [exten@sub-record-check:11] Gosub(“Local/204@from-queue-0001bc2c;2”, “recordcheck,1(dontcare,external,204)”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp(“Local/204@from-queue-0001bc2c;2”, “Starting recording check against dontcare”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [recordcheck@sub-record-check:2] Goto(“Local/204@from-queue-0001bc2c;2”, “dontcare”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [recordcheck@sub-record-check:3] Return(“Local/204@from-queue-0001bc2c;2”, “”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [exten@sub-record-check:12] Return(“Local/204@from-queue-0001bc2c;2”, “”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-exten-vm:7] GotoIf(“Local/204@from-queue-0001bc2c;2”, “1?macrodial”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx_builtins.c: Goto (macro-exten-vm,s,13)
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-exten-vm:13] GosubIf(“Local/204@from-queue-0001bc2c;2”, “0?clrheader,1()”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-exten-vm:14] Macro(“Local/204@from-queue-0001bc2c;2”, “dial-one,15,HhTtrM(auto-blkvm),204”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-dial-one:1] Set(“Local/204@from-queue-0001bc2c;2”, “DEXTEN=204”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-dial-one:2] Set(“Local/204@from-queue-0001bc2c;2”, “__CRM_SOURCE=OBFUSCATED”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-dial-one:3] UserEvent(“Local/204@from-queue-0001bc2c;2”, “zulu-inbound-call,type:macro-dial-one,from:OBFUSCATED,to:204”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-dial-one:4] ExecIf(“Local/204@from-queue-0001bc2c;2”, “0?Set(__EXTTOCALL=204)”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-dial-one:5] Set(“Local/204@from-queue-0001bc2c;2”, “DIALSTATUS_CW=”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-dial-one:6] GosubIf(“Local/204@from-queue-0001bc2c;2”, “0?screen,1()”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-dial-one:7] GosubIf(“Local/204@from-queue-0001bc2c;2”, “0?cf,1()”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-dial-one:8] GotoIf(“Local/204@from-queue-0001bc2c;2”, “1?skip1”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx_builtins.c: Goto (macro-dial-one,s,11)
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-dial-one:11] GotoIf(“Local/204@from-queue-0001bc2c;2”, “0?nodial”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-dial-one:12] GotoIf(“Local/204@from-queue-0001bc2c;2”, “0?continue”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-dial-one:13] Set(“Local/204@from-queue-0001bc2c;2”, “EXTHASCW=”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-dial-one:14] GotoIf(“Local/204@from-queue-0001bc2c;2”, “1?next1:cwinusebusy”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx_builtins.c: Goto (macro-dial-one,s,15)
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-dial-one:15] GotoIf(“Local/204@from-queue-0001bc2c;2”, “0?docfu:skip3”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx_builtins.c: Goto (macro-dial-one,s,19)
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-dial-one:19] GotoIf(“Local/204@from-queue-0001bc2c;2”, “1?next2:continue”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx_builtins.c: Goto (macro-dial-one,s,20)
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-dial-one:20] GotoIf(“Local/204@from-queue-0001bc2c;2”, “1?continue”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx_builtins.c: Goto (macro-dial-one,s,28)
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-dial-one:28] GotoIf(“Local/204@from-queue-0001bc2c;2”, “0?nodial”) in new stack

5

[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-dial-one:29] GosubIf(“Local/204@from-queue-0001bc2c;2”, “1?dstring,1():dlocal,1()”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:1] Set(“Local/204@from-queue-0001bc2c;2”, “DSTRING=”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:2] Set(“Local/204@from-queue-0001bc2c;2”, “DEVICES=204”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:3] ExecIf(“Local/204@from-queue-0001bc2c;2”, “0?Return()”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:4] ExecIf(“Local/204@from-queue-0001bc2c;2”, “0?Set(DEVICES=04)”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:5] Set(“Local/204@from-queue-0001bc2c;2”, “LOOPCNT=1”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:6] Set(“Local/204@from-queue-0001bc2c;2”, “ITER=1”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:7] Set(“Local/204@from-queue-0001bc2c;2”, “THISDIAL=PJSIP/204”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:8] GosubIf(“Local/204@from-queue-0001bc2c;2”, “1?zap2dahdi,1()”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [zap2dahdi@macro-dial-one:1] ExecIf(“Local/204@from-queue-0001bc2c;2”, “0?Return()”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [zap2dahdi@macro-dial-one:2] Set(“Local/204@from-queue-0001bc2c;2”, “NEWDIAL=”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [zap2dahdi@macro-dial-one:3] Set(“Local/204@from-queue-0001bc2c;2”, “LOOPCNT2=1”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [zap2dahdi@macro-dial-one:4] Set(“Local/204@from-queue-0001bc2c;2”, “ITER2=1”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [zap2dahdi@macro-dial-one:5] Set(“Local/204@from-queue-0001bc2c;2”, “THISPART2=PJSIP/204”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [zap2dahdi@macro-dial-one:6] ExecIf(“Local/204@from-queue-0001bc2c;2”, “0?Set(THISPART2=DAHDIIP/204)”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [zap2dahdi@macro-dial-one:7] Set(“Local/204@from-queue-0001bc2c;2”, “NEWDIAL=PJSIP/204&”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [zap2dahdi@macro-dial-one:8] Set(“Local/204@from-queue-0001bc2c;2”, “ITER2=2”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [zap2dahdi@macro-dial-one:9] GotoIf(“Local/204@from-queue-0001bc2c;2”, “0?begin2”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [zap2dahdi@macro-dial-one:10] Set(“Local/204@from-queue-0001bc2c;2”, “THISDIAL=PJSIP/204”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [zap2dahdi@macro-dial-one:11] Return(“Local/204@from-queue-0001bc2c;2”, “”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:9] GotoIf(“Local/204@from-queue-0001bc2c;2”, “0?docheck”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:10] NoOp(“Local/204@from-queue-0001bc2c;2”, “Debug: Found PJSIP Destination PJSIP/204”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:11] GotoIf(“Local/204@from-queue-0001bc2c;2”, “0?doset”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:12] NoOp(“Local/204@from-queue-0001bc2c;2”, “Debug: Updating PJSIP Destination with PJSIP_DIAL_CONTACTS”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:13] Set(“Local/204@from-queue-0001bc2c;2”, “THISDIAL=PJSIP/204/sip:[email protected]:5060”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:14] ExecIf(“Local/204@from-queue-0001bc2c;2”, “0?Set(DIALSTATUS=CHANUNAVAIL)”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:15] GotoIf(“Local/204@from-queue-0001bc2c;2”, “0?skipset”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:16] Set(“Local/204@from-queue-0001bc2c;2”, “DSTRING=PJSIP/204/sip:[email protected]:5060&”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:17] Set(“Local/204@from-queue-0001bc2c;2”, “ITER=2”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:18] GotoIf(“Local/204@from-queue-0001bc2c;2”, “0?begin”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:19] ExecIf(“Local/204@from-queue-0001bc2c;2”, “0?Return()”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:20] Set(“Local/204@from-queue-0001bc2c;2”, “DSTRING=PJSIP/204/sip:[email protected]:5060”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:21] Return(“Local/204@from-queue-0001bc2c;2”, “”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-dial-one:30] GotoIf(“Local/204@from-queue-0001bc2c;2”, “0?nodial”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-dial-one:31] GotoIf(“Local/204@from-queue-0001bc2c;2”, “0?skiptrace”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-dial-one:32] GosubIf(“Local/204@from-queue-0001bc2c;2”, “1?ctset,1():ctclear,1()”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [ctset@macro-dial-one:1] Set(“Local/204@from-queue-0001bc2c;2”, “DB(CALLTRACE/204)=OBFUSCATED”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [ctset@macro-dial-one:2] Return(“Local/204@from-queue-0001bc2c;2”, “”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-dial-one:33] Set(“Local/204@from-queue-0001bc2c;2”, “D_OPTIONS=HhTtrM(auto-blkvm)”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-dial-one:34] GosubIf(“Local/204@from-queue-0001bc2c;2”, “0?func-set-sipheader,s,1(Alert-Info,)”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-dial-one:35] NoOp(“Local/204@from-queue-0001bc2c;2”, "Blind Transfer: , Attended Transfer: , User: OBFUSCATED, Alert Info: ") in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-dial-one:36] ExecIf(“Local/204@from-queue-0001bc2c;2”, “1?Set(ALERT_INFO=)”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-dial-one:37] ExecIf(“Local/204@from-queue-0001bc2c;2”, “0?Set(ALERT_INFO=)”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-dial-one:38] ExecIf(“Local/204@from-queue-0001bc2c;2”, “0?Set(ALERT_INFO=)”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-dial-one:39] ExecIf(“Local/204@from-queue-0001bc2c;2”, “0?Set(ALERT_INFO=Normal;volume=)”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-dial-one:40] ExecIf(“Local/204@from-queue-0001bc2c;2”, “0?Set(ALERT_INFO=Normal;volume=)”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-dial-one:41] GosubIf(“Local/204@from-queue-0001bc2c;2”, “0?func-set-sipheader,s,1(Alert-Info,)”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-dial-one:42] ExecIf(“Local/204@from-queue-0001bc2c;2”, “0?Set(CHANNEL(musicclass)=)”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-dial-one:43] GosubIf(“Local/204@from-queue-0001bc2c;2”, “0?qwait,1()”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-dial-one:44] Set(“Local/204@from-queue-0001bc2c;2”, “__CWIGNORE=TRUE”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-dial-one:45] Set(“Local/204@from-queue-0001bc2c;2”, “__KEEPCID=TRUE”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-dial-one:46] GotoIf(“Local/204@from-queue-0001bc2c;2”, “0?usegoto,1”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-dial-one:47] GotoIf(“Local/204@from-queue-0001bc2c;2”, “1?godial”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx_builtins.c: Goto (macro-dial-one,s,52)
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-dial-one:52] Macro(“Local/204@from-queue-0001bc2c;2”, “dialout-one-predial-hook,”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-dialout-one-predial-hook:1] MacroExit(“Local/204@from-queue-0001bc2c;2”, “”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-dial-one:53] ExecIf(“Local/204@from-queue-0001bc2c;2”, “1?Set(D_OPTIONS=HhtrM(auto-blkvm)I)”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-dial-one:54] GotoIf(“Local/204@from-queue-0001bc2c;2”, “1?zulunourl”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx_builtins.c: Goto (macro-dial-one,s,56)
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-dial-one:56] ExecIf(“Local/204@from-queue-0001bc2c;2”, “0?Set(ZULU_TYPE=)”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-dial-one:57] GotoIf(“Local/204@from-queue-0001bc2c;2”, “1?skippopup”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx_builtins.c: Goto (macro-dial-one,s,64)
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-dial-one:64] UserEvent(“Local/204@from-queue-0001bc2c;2”, “zulu-call,eventtype:calling,extension:204,type:,url:,cnam:OBFUSCATED,cnum:OBFUSCATED,lid:1551117592.541446,from:OBFUSCATED,to:204,user:”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-dial-one:65] NoOp(“Local/204@from-queue-0001bc2c;2”, “Zulu Finished”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-dial-one:66] Set(“Local/204@from-queue-0001bc2c;2”, “_zuludialopts=”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-dial-one:67] AGI(“Local/204@from-queue-0001bc2c;2”, “zulureplace-one.agi,DSTRING,90”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/zulureplace-one.agi
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] res_agi.c: zulureplace-one.agi,DSTRING,90: DIALSTRING: PJSIP/204/sip:[email protected]:5060
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] res_agi.c: zulureplace-one.agi,DSTRING,90: DIALOPTS: HhtrM(auto-blkvm)I
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] res_agi.c: zulureplace-one.agi,DSTRING,90: TIMEOUT: 15
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] res_agi.c: zulureplace-one.agi,DSTRING,90: NEWDIALSTRING: PJSIP/204/sip:[email protected]:5060
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] res_agi.c: <Local/204@from-queue-0001bc2c;2>AGI Script zulureplace-one.agi completed, returning 0
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-dial-one:68] NoOp(“Local/204@from-queue-0001bc2c;2”, “”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-dial-one:69] ExecIf(“Local/204@from-queue-0001bc2c;2”, “0?Set(D_OPTIONS=HhtrM(auto-blkvm)Ig)”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-dial-one:70] Dial(“Local/204@from-queue-0001bc2c;2”, “PJSIP/204/sip:[email protected]:5060,15,HhtrM(auto-blkvm)Ib(func-apply-sipheaders^s^1)”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] app_stack.c: PJSIP/204-00039739 Internal Gosub(func-apply-sipheaders,s,1) start
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf(“PJSIP/204-00039739”, “1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@func-apply-sipheaders:2] UserEvent(“PJSIP/204-00039739”, “zulu-call-b,type:func-apply-sipheaders,to:204,from:OBFUSCATED”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@func-apply-sipheaders:3] NoOp(“PJSIP/204-00039739”, “Applying SIP Headers to channel”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@func-apply-sipheaders:4] Set(“PJSIP/204-00039739”, “SIPHEADERKEYS=”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@func-apply-sipheaders:5] ExecIf(“PJSIP/204-00039739”, “0?Set(Rheader=1)”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@func-apply-sipheaders:6] While(“PJSIP/204-00039739”, “0”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] app_while.c: Jumping to priority 10
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@func-apply-sipheaders:11] ExecIf(“PJSIP/204-00039739”, “0?SIPRemoveHeader(Alert-Info:)”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@func-apply-sipheaders:12] ExecIf(“PJSIP/204-00039739”, “0?Set(PJSIP_HEADER(remove,Alert-Info)=)”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@func-apply-sipheaders:13] Return(“PJSIP/204-00039739”, “”) in new stack
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] app_stack.c: Spawn extension (from-internal, 204, 1) exited non-zero on ‘PJSIP/204-00039739’
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] app_stack.c: PJSIP/204-00039739 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] app_dial.c: Called PJSIP/204/sip:[email protected]:5060
[2019-02-25 10:00:26] VERBOSE[27209][C-0001651d] app_queue.c: Local/204@from-queue-0001bc2c;1 is ringing
[2019-02-25 10:00:26] VERBOSE[16869] netsock2.c: Using SIP RTP Audio TOS bits 184
[2019-02-25 10:00:26] VERBOSE[16869] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field.
[2019-02-25 10:00:26] VERBOSE[16869] netsock2.c: Using SIP RTP Audio CoS mark 5
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] app_dial.c: Connected line update to Local/204@from-queue-0001bc2c;2 prevented.
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] app_dial.c: PJSIP/204-00039739 is ringing
[2019-02-25 10:00:26] VERBOSE[27490][C-0001651d] app_dial.c: PJSIP/204-00039739 is ringing
[2019-02-25 10:00:26] VERBOSE[27209][C-0001651d] app_queue.c: Local/204@from-queue-0001bc2c;1 is ringing
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] app_dial.c: Nobody picked up in 15000 ms
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] app_stack.c: PJSIP/204-00039739 Internal Gosub(crm-hangup,s,1) start
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@crm-hangup:1] NoOp(“PJSIP/204-00039739”, “Sending Hangup to CRM”) in new stack
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@crm-hangup:2] NoOp(“PJSIP/204-00039739”, “HANGUP CAUSE: 0”) in new stack
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@crm-hangup:3] ExecIf(“PJSIP/204-00039739”, “0?Set(__CRM_VOICEMAIL=)”) in new stack
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@crm-hangup:4] NoOp(“PJSIP/204-00039739”, “MASTER CHANNEL: 1551117626.541455 = 1551117592.541446”) in new stack
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@crm-hangup:5] GotoIf(“PJSIP/204-00039739”, “1?return”) in new stack
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] pbx_builtins.c: Goto (crm-hangup,s,8)
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@crm-hangup:8] Return(“PJSIP/204-00039739”, “”) in new stack
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] app_stack.c: Spawn extension (from-internal, 204, 1) exited non-zero on ‘PJSIP/204-00039739’
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] app_stack.c: PJSIP/204-00039739 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-dial-one:71] ExecIf(“Local/204@from-queue-0001bc2c;2”, “0?MacroExit()”) in new stack
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-dial-one:72] ExecIf(“Local/204@from-queue-0001bc2c;2”, “0?Set(DIALSTATUS=)”) in new stack
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-dial-one:73] GosubIf(“Local/204@from-queue-0001bc2c;2”, “0?s-NOANSWER,1()”) in new stack
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-dial-one:74] MacroExit(“Local/204@from-queue-0001bc2c;2”, “”) in new stack
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-exten-vm:15] Set(“Local/204@from-queue-0001bc2c;2”, “SV_DIALSTATUS=NOANSWER”) in new stack
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-exten-vm:16] GosubIf(“Local/204@from-queue-0001bc2c;2”, “0?docfu,1()”) in new stack
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-exten-vm:17] GosubIf(“Local/204@from-queue-0001bc2c;2”, “0?docfb,1()”) in new stack
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-exten-vm:18] Set(“Local/204@from-queue-0001bc2c;2”, “DIALSTATUS=NOANSWER”) in new stack
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-exten-vm:19] ExecIf(“Local/204@from-queue-0001bc2c;2”, “0?MacroExit()”) in new stack
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-exten-vm:20] GotoIf(“Local/204@from-queue-0001bc2c;2”, “0?s-NOANSWER,1”) in new stack
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-exten-vm:21] Macro(“Local/204@from-queue-0001bc2c;2”, “vm,204,NOANSWER,”) in new stack
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-vm:1] Macro(“Local/204@from-queue-0001bc2c;2”, “user-callerid,SKIPTTL”) in new stack
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-user-callerid:1] Set(“Local/204@from-queue-0001bc2c;2”, “TOUCH_MONITOR=1551117626.541454”) in new stack
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-user-callerid:2] Set(“Local/204@from-queue-0001bc2c;2”, “AMPUSER=OBFUSCATED”) in new stack
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-user-callerid:3] GotoIf(“Local/204@from-queue-0001bc2c;2”, “1?report”) in new stack
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] pbx_builtins.c: Goto (macro-user-callerid,s,16)
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-user-callerid:16] NoOp(“Local/204@from-queue-0001bc2c;2”, “Macro Depth is 3”) in new stack
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-user-callerid:17] GotoIf(“Local/204@from-queue-0001bc2c;2”, “1?report2:macroerror”) in new stack
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] pbx_builtins.c: Goto (macro-user-callerid,s,18)
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-user-callerid:18] GotoIf(“Local/204@from-queue-0001bc2c;2”, “1?continue”) in new stack
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] pbx_builtins.c: Goto (macro-user-callerid,s,37)
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-user-callerid:37] Set(“Local/204@from-queue-0001bc2c;2”, “CALLERID(number)=OBFUSCATED”) in new stack
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-user-callerid:38] Set(“Local/204@from-queue-0001bc2c;2”, “CALLERID(name)=OBFUSCATED”) in new stack
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-user-callerid:39] GotoIf(“Local/204@from-queue-0001bc2c;2”, “0?cnum”) in new stack
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-user-callerid:40] Set(“Local/204@from-queue-0001bc2c;2”, “CDR(cnam)=OBFUSCATED”) in new stack
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-user-callerid:41] Set(“Local/204@from-queue-0001bc2c;2”, “CDR(cnum)=OBFUSCATED”) in new stack
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-user-callerid:42] Set(“Local/204@from-queue-0001bc2c;2”, “CHANNEL(language)=en”) in new stack
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-user-callerid:43] ExecIf(“Local/204@from-queue-0001bc2c;2”, “0?Set(CALLERID(name)=)”) in new stack
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-user-callerid:44] GosubIf(“Local/204@from-queue-0001bc2c;2”, “0?app-check-classofservce,s,1()”) in new stack
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-vm:2] Set(“Local/204@from-queue-0001bc2c;2”, “VMGAIN=”) in new stack
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-vm:3] Macro(“Local/204@from-queue-0001bc2c;2”, “blkvm-check,”) in new stack
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-blkvm-check:1] Set(“Local/204@from-queue-0001bc2c;2”, “GOSUB_RETVAL=TRUE”) in new stack
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-blkvm-check:2] ExecIf(“Local/204@from-queue-0001bc2c;2”, “0?Set(GOSUB_RETVAL=TRUE)”) in new stack
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-blkvm-check:3] MacroExit(“Local/204@from-queue-0001bc2c;2”, “”) in new stack
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-vm:4] GotoIf(“Local/204@from-queue-0001bc2c;2”, “0?vmx,1”) in new stack
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-vm:5] Hangup(“Local/204@from-queue-0001bc2c;2”, “”) in new stack
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] app_macro.c: Spawn extension (macro-vm, s, 5) exited non-zero on ‘Local/204@from-queue-0001bc2c;2’ in macro ‘vm’
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] app_macro.c: Spawn extension (macro-exten-vm, s, 21) exited non-zero on ‘Local/204@from-queue-0001bc2c;2’ in macro ‘exten-vm’
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] pbx.c: Spawn extension (ext-local, 204, 2) exited non-zero on ‘Local/204@from-queue-0001bc2c;2’
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] pbx.c: Executing [h@ext-local:1] Macro(“Local/204@from-queue-0001bc2c;2”, “hangupcall,”) in new stack
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-hangupcall:1] GotoIf(“Local/204@from-queue-0001bc2c;2”, “1?theend”) in new stack
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-hangupcall:3] ExecIf(“Local/204@from-queue-0001bc2c;2”, “0?Set(CDR(recordingfile)=)”) in new stack
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-hangupcall:4] NoOp(“Local/204@from-queue-0001bc2c;2”, " monior file= ") in new stack
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-hangupcall:5] AGI(“Local/204@from-queue-0001bc2c;2”, “attendedtransfer-rec-restart.php,”) in new stack
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/attendedtransfer-rec-restart.php
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] res_agi.c: <Local/204@from-queue-0001bc2c;2>AGI Script attendedtransfer-rec-restart.php completed, returning 0
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] pbx.c: Executing [s@macro-hangupcall:6] Hangup(“Local/204@from-queue-0001bc2c;2”, “”) in new stack
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] app_macro.c: Spawn extension (macro-hangupcall, s, 6) exited non-zero on ‘Local/204@from-queue-0001bc2c;2’ in macro ‘hangupcall’
[2019-02-25 10:00:41] VERBOSE[27490][C-0001651d] pbx.c: Spawn extension (ext-local, h, 1) exited non-zero on ‘Local/204@from-queue-0001bc2c;2’
[2019-02-25 10:00:41] VERBOSE[27209][C-0001651d] app_queue.c: Nobody picked up in 15000 ms
[2019-02-25 10:00:41] VERBOSE[27209][C-0001651d] app_queue.c: Called Local/203@from-queue/n
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [203@from-queue:1] Set(“Local/203@from-queue-0001bc2d;2”, “QAGENT=203”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [203@from-queue:2] Set(“Local/203@from-queue-0001bc2d;2”, “__FROMQ=true”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [203@from-queue:3] GotoIf(“Local/203@from-queue-0001bc2d;2”, “0?hangup”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [203@from-queue:4] GotoIf(“Local/203@from-queue-0001bc2d;2”, “1?102,1:hangup”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx_builtins.c: Goto (from-queue,102,1)
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [102@from-queue:1] Goto(“Local/203@from-queue-0001bc2d;2”, “from-internal,203,1”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx_builtins.c: Goto (from-internal,203,1)
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [203@from-internal:1] GotoIf(“Local/203@from-queue-0001bc2d;2”, “1?ext-local,203,1:followme-check,203,1”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx_builtins.c: Goto (ext-local,203,1)
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [203@ext-local:1] Set(“Local/203@from-queue-0001bc2d;2”, “__RINGTIMER=15”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [203@ext-local:2] Macro(“Local/203@from-queue-0001bc2d;2”, “exten-vm,203,203,0,0,0”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-exten-vm:1] Macro(“Local/203@from-queue-0001bc2d;2”, “user-callerid,”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-user-callerid:1] Set(“Local/203@from-queue-0001bc2d;2”, “TOUCH_MONITOR=1551117641.541457”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-user-callerid:2] Set(“Local/203@from-queue-0001bc2d;2”, “AMPUSER=OBFUSCATED”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-user-callerid:3] GotoIf(“Local/203@from-queue-0001bc2d;2”, “1?report”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx_builtins.c: Goto (macro-user-callerid,s,16)
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-user-callerid:16] NoOp(“Local/203@from-queue-0001bc2d;2”, “Macro Depth is 2”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-user-callerid:17] GotoIf(“Local/203@from-queue-0001bc2d;2”, “1?report2:macroerror”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx_builtins.c: Goto (macro-user-callerid,s,18)
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-user-callerid:18] GotoIf(“Local/203@from-queue-0001bc2d;2”, “0?continue”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-user-callerid:19] ExecIf(“Local/203@from-queue-0001bc2d;2”, “1?Set(__CALLEE_ACCOUNCODE=)”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-user-callerid:20] Set(“Local/203@from-queue-0001bc2d;2”, “__TTL=63”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-user-callerid:21] GotoIf(“Local/203@from-queue-0001bc2d;2”, “1?continue”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx_builtins.c: Goto (macro-user-callerid,s,37)
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-user-callerid:37] Set(“Local/203@from-queue-0001bc2d;2”, “CALLERID(number)=OBFUSCATED”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-user-callerid:38] Set(“Local/203@from-queue-0001bc2d;2”, “CALLERID(name)=OBFUSCATED”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-user-callerid:39] GotoIf(“Local/203@from-queue-0001bc2d;2”, “0?cnum”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-user-callerid:40] Set(“Local/203@from-queue-0001bc2d;2”, “CDR(cnam)=OBFUSCATED”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-user-callerid:41] Set(“Local/203@from-queue-0001bc2d;2”, “CDR(cnum)=OBFUSCATED”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-user-callerid:42] Set(“Local/203@from-queue-0001bc2d;2”, “CHANNEL(language)=en”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-user-callerid:43] ExecIf(“Local/203@from-queue-0001bc2d;2”, “0?Set(CALLERID(name)=)”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-user-callerid:44] GosubIf(“Local/203@from-queue-0001bc2d;2”, “0?app-check-classofservce,s,1()”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-exten-vm:2] Set(“Local/203@from-queue-0001bc2d;2”, “RingGroupMethod=none”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-exten-vm:3] Set(“Local/203@from-queue-0001bc2d;2”, “__EXTTOCALL=203”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-exten-vm:4] Set(“Local/203@from-queue-0001bc2d;2”, “__PICKUPMARK=203”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-exten-vm:5] Set(“Local/203@from-queue-0001bc2d;2”, “RT=15”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-exten-vm:6] Gosub(“Local/203@from-queue-0001bc2d;2”, “sub-record-check,s,1(exten,203,dontcare)”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@sub-record-check:1] GotoIf(“Local/203@from-queue-0001bc2d;2”, “0?initialized”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@sub-record-check:2] Set(“Local/203@from-queue-0001bc2d;2”, “__REC_STATUS=INITIALIZED”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@sub-record-check:3] Set(“Local/203@from-queue-0001bc2d;2”, “NOW=1551117641”) in new stack

6

[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@sub-record-check:4] Set(“Local/203@from-queue-0001bc2d;2”, “__DAY=25”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@sub-record-check:5] Set(“Local/203@from-queue-0001bc2d;2”, “__MONTH=02”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@sub-record-check:6] Set(“Local/203@from-queue-0001bc2d;2”, “__YEAR=2019”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@sub-record-check:7] Set(“Local/203@from-queue-0001bc2d;2”, “__TIMESTR=20190225-100041”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@sub-record-check:8] Set(“Local/203@from-queue-0001bc2d;2”, “__FROMEXTEN=OBFUSCATED”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@sub-record-check:9] Set(“Local/203@from-queue-0001bc2d;2”, “__MON_FMT=wav”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@sub-record-check:10] NoOp(“Local/203@from-queue-0001bc2d;2”, “Recordings initialized”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@sub-record-check:11] ExecIf(“Local/203@from-queue-0001bc2d;2”, “0?Set(ARG3=dontcare)”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@sub-record-check:12] Set(“Local/203@from-queue-0001bc2d;2”, “REC_POLICY_MODE_SAVE=”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@sub-record-check:13] ExecIf(“Local/203@from-queue-0001bc2d;2”, “0?Set(REC_STATUS=NO)”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@sub-record-check:14] GotoIf(“Local/203@from-queue-0001bc2d;2”, “5?checkaction”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx_builtins.c: Goto (sub-record-check,s,17)
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@sub-record-check:17] GotoIf(“Local/203@from-queue-0001bc2d;2”, “1?sub-record-check,exten,1”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx_builtins.c: Goto (sub-record-check,exten,1)
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [exten@sub-record-check:1] NoOp(“Local/203@from-queue-0001bc2d;2”, “Exten Recording Check between OBFUSCATED and 203”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [exten@sub-record-check:2] Set(“Local/203@from-queue-0001bc2d;2”, “CALLTYPE=external”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [exten@sub-record-check:3] ExecIf(“Local/203@from-queue-0001bc2d;2”, “0?Set(CALLTYPE=)”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [exten@sub-record-check:4] Set(“Local/203@from-queue-0001bc2d;2”, “CALLEE=dontcare”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [exten@sub-record-check:5] ExecIf(“Local/203@from-queue-0001bc2d;2”, “0?Set(CALLEE=dontcare)”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [exten@sub-record-check:6] GotoIf(“Local/203@from-queue-0001bc2d;2”, “1?callee”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx_builtins.c: Goto (sub-record-check,exten,11)
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [exten@sub-record-check:11] Gosub(“Local/203@from-queue-0001bc2d;2”, “recordcheck,1(dontcare,external,203)”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp(“Local/203@from-queue-0001bc2d;2”, “Starting recording check against dontcare”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [recordcheck@sub-record-check:2] Goto(“Local/203@from-queue-0001bc2d;2”, “dontcare”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [recordcheck@sub-record-check:3] Return(“Local/203@from-queue-0001bc2d;2”, “”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [exten@sub-record-check:12] Return(“Local/203@from-queue-0001bc2d;2”, “”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-exten-vm:7] GotoIf(“Local/203@from-queue-0001bc2d;2”, “1?macrodial”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx_builtins.c: Goto (macro-exten-vm,s,13)
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-exten-vm:13] GosubIf(“Local/203@from-queue-0001bc2d;2”, “0?clrheader,1()”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-exten-vm:14] Macro(“Local/203@from-queue-0001bc2d;2”, “dial-one,15,HhTtrM(auto-blkvm),203”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-dial-one:1] Set(“Local/203@from-queue-0001bc2d;2”, “DEXTEN=203”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-dial-one:2] Set(“Local/203@from-queue-0001bc2d;2”, “__CRM_SOURCE=OBFUSCATED”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-dial-one:3] UserEvent(“Local/203@from-queue-0001bc2d;2”, “zulu-inbound-call,type:macro-dial-one,from:OBFUSCATED,to:203”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-dial-one:4] ExecIf(“Local/203@from-queue-0001bc2d;2”, “0?Set(__EXTTOCALL=203)”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-dial-one:5] Set(“Local/203@from-queue-0001bc2d;2”, “DIALSTATUS_CW=”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-dial-one:6] GosubIf(“Local/203@from-queue-0001bc2d;2”, “0?screen,1()”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-dial-one:7] GosubIf(“Local/203@from-queue-0001bc2d;2”, “0?cf,1()”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-dial-one:8] GotoIf(“Local/203@from-queue-0001bc2d;2”, “1?skip1”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx_builtins.c: Goto (macro-dial-one,s,11)
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-dial-one:11] GotoIf(“Local/203@from-queue-0001bc2d;2”, “0?nodial”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-dial-one:12] GotoIf(“Local/203@from-queue-0001bc2d;2”, “0?continue”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-dial-one:13] Set(“Local/203@from-queue-0001bc2d;2”, “EXTHASCW=”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-dial-one:14] GotoIf(“Local/203@from-queue-0001bc2d;2”, “1?next1:cwinusebusy”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx_builtins.c: Goto (macro-dial-one,s,15)
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-dial-one:15] GotoIf(“Local/203@from-queue-0001bc2d;2”, “0?docfu:skip3”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx_builtins.c: Goto (macro-dial-one,s,19)
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-dial-one:19] GotoIf(“Local/203@from-queue-0001bc2d;2”, “1?next2:continue”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx_builtins.c: Goto (macro-dial-one,s,20)
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-dial-one:20] GotoIf(“Local/203@from-queue-0001bc2d;2”, “1?continue”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx_builtins.c: Goto (macro-dial-one,s,28)
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-dial-one:28] GotoIf(“Local/203@from-queue-0001bc2d;2”, “0?nodial”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-dial-one:29] GosubIf(“Local/203@from-queue-0001bc2d;2”, “1?dstring,1():dlocal,1()”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:1] Set(“Local/203@from-queue-0001bc2d;2”, “DSTRING=”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:2] Set(“Local/203@from-queue-0001bc2d;2”, “DEVICES=203”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:3] ExecIf(“Local/203@from-queue-0001bc2d;2”, “0?Return()”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:4] ExecIf(“Local/203@from-queue-0001bc2d;2”, “0?Set(DEVICES=03)”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:5] Set(“Local/203@from-queue-0001bc2d;2”, “LOOPCNT=1”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:6] Set(“Local/203@from-queue-0001bc2d;2”, “ITER=1”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:7] Set(“Local/203@from-queue-0001bc2d;2”, “THISDIAL=PJSIP/203”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:8] GosubIf(“Local/203@from-queue-0001bc2d;2”, “1?zap2dahdi,1()”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [zap2dahdi@macro-dial-one:1] ExecIf(“Local/203@from-queue-0001bc2d;2”, “0?Return()”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [zap2dahdi@macro-dial-one:2] Set(“Local/203@from-queue-0001bc2d;2”, “NEWDIAL=”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [zap2dahdi@macro-dial-one:3] Set(“Local/203@from-queue-0001bc2d;2”, “LOOPCNT2=1”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [zap2dahdi@macro-dial-one:4] Set(“Local/203@from-queue-0001bc2d;2”, “ITER2=1”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [zap2dahdi@macro-dial-one:5] Set(“Local/203@from-queue-0001bc2d;2”, “THISPART2=PJSIP/203”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [zap2dahdi@macro-dial-one:6] ExecIf(“Local/203@from-queue-0001bc2d;2”, “0?Set(THISPART2=DAHDIIP/203)”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [zap2dahdi@macro-dial-one:7] Set(“Local/203@from-queue-0001bc2d;2”, “NEWDIAL=PJSIP/203&”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [zap2dahdi@macro-dial-one:8] Set(“Local/203@from-queue-0001bc2d;2”, “ITER2=2”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [zap2dahdi@macro-dial-one:9] GotoIf(“Local/203@from-queue-0001bc2d;2”, “0?begin2”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [zap2dahdi@macro-dial-one:10] Set(“Local/203@from-queue-0001bc2d;2”, “THISDIAL=PJSIP/203”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [zap2dahdi@macro-dial-one:11] Return(“Local/203@from-queue-0001bc2d;2”, “”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:9] GotoIf(“Local/203@from-queue-0001bc2d;2”, “0?docheck”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:10] NoOp(“Local/203@from-queue-0001bc2d;2”, “Debug: Found PJSIP Destination PJSIP/203”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:11] GotoIf(“Local/203@from-queue-0001bc2d;2”, “0?doset”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:12] NoOp(“Local/203@from-queue-0001bc2d;2”, “Debug: Updating PJSIP Destination with PJSIP_DIAL_CONTACTS”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:13] Set(“Local/203@from-queue-0001bc2d;2”, “THISDIAL=PJSIP/203/sip:[email protected]:5060”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:14] ExecIf(“Local/203@from-queue-0001bc2d;2”, “0?Set(DIALSTATUS=CHANUNAVAIL)”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:15] GotoIf(“Local/203@from-queue-0001bc2d;2”, “0?skipset”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:16] Set(“Local/203@from-queue-0001bc2d;2”, “DSTRING=PJSIP/203/sip:[email protected]:5060&”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:17] Set(“Local/203@from-queue-0001bc2d;2”, “ITER=2”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:18] GotoIf(“Local/203@from-queue-0001bc2d;2”, “0?begin”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:19] ExecIf(“Local/203@from-queue-0001bc2d;2”, “0?Return()”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:20] Set(“Local/203@from-queue-0001bc2d;2”, “DSTRING=PJSIP/203/sip:[email protected]:5060”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:21] Return(“Local/203@from-queue-0001bc2d;2”, “”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-dial-one:30] GotoIf(“Local/203@from-queue-0001bc2d;2”, “0?nodial”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-dial-one:31] GotoIf(“Local/203@from-queue-0001bc2d;2”, “0?skiptrace”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-dial-one:32] GosubIf(“Local/203@from-queue-0001bc2d;2”, “1?ctset,1():ctclear,1()”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [ctset@macro-dial-one:1] Set(“Local/203@from-queue-0001bc2d;2”, “DB(CALLTRACE/203)=OBFUSCATED”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [ctset@macro-dial-one:2] Return(“Local/203@from-queue-0001bc2d;2”, “”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-dial-one:33] Set(“Local/203@from-queue-0001bc2d;2”, “D_OPTIONS=HhTtrM(auto-blkvm)”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-dial-one:34] GosubIf(“Local/203@from-queue-0001bc2d;2”, “0?func-set-sipheader,s,1(Alert-Info,)”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-dial-one:35] NoOp(“Local/203@from-queue-0001bc2d;2”, "Blind Transfer: , Attended Transfer: , User: OBFUSCATED, Alert Info: ") in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-dial-one:36] ExecIf(“Local/203@from-queue-0001bc2d;2”, “1?Set(ALERT_INFO=)”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-dial-one:37] ExecIf(“Local/203@from-queue-0001bc2d;2”, “0?Set(ALERT_INFO=)”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-dial-one:38] ExecIf(“Local/203@from-queue-0001bc2d;2”, “0?Set(ALERT_INFO=)”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-dial-one:39] ExecIf(“Local/203@from-queue-0001bc2d;2”, “0?Set(ALERT_INFO=Normal;volume=)”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-dial-one:40] ExecIf(“Local/203@from-queue-0001bc2d;2”, “0?Set(ALERT_INFO=Normal;volume=)”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-dial-one:41] GosubIf(“Local/203@from-queue-0001bc2d;2”, “0?func-set-sipheader,s,1(Alert-Info,)”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-dial-one:42] ExecIf(“Local/203@from-queue-0001bc2d;2”, “0?Set(CHANNEL(musicclass)=)”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-dial-one:43] GosubIf(“Local/203@from-queue-0001bc2d;2”, “0?qwait,1()”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-dial-one:44] Set(“Local/203@from-queue-0001bc2d;2”, “__CWIGNORE=TRUE”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-dial-one:45] Set(“Local/203@from-queue-0001bc2d;2”, “__KEEPCID=TRUE”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-dial-one:46] GotoIf(“Local/203@from-queue-0001bc2d;2”, “0?usegoto,1”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-dial-one:47] GotoIf(“Local/203@from-queue-0001bc2d;2”, “1?godial”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx_builtins.c: Goto (macro-dial-one,s,52)
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-dial-one:52] Macro(“Local/203@from-queue-0001bc2d;2”, “dialout-one-predial-hook,”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-dialout-one-predial-hook:1] MacroExit(“Local/203@from-queue-0001bc2d;2”, “”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-dial-one:53] ExecIf(“Local/203@from-queue-0001bc2d;2”, “1?Set(D_OPTIONS=HhtrM(auto-blkvm)I)”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-dial-one:54] GotoIf(“Local/203@from-queue-0001bc2d;2”, “1?zulunourl”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx_builtins.c: Goto (macro-dial-one,s,56)
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-dial-one:56] ExecIf(“Local/203@from-queue-0001bc2d;2”, “0?Set(ZULU_TYPE=)”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-dial-one:57] GotoIf(“Local/203@from-queue-0001bc2d;2”, “1?skippopup”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx_builtins.c: Goto (macro-dial-one,s,64)
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-dial-one:64] UserEvent(“Local/203@from-queue-0001bc2d;2”, “zulu-call,eventtype:calling,extension:203,type:,url:,cnam:OBFUSCATED,cnum:OBFUSCATED,lid:1551117592.541446,from:OBFUSCATED,to:203,user:”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-dial-one:65] NoOp(“Local/203@from-queue-0001bc2d;2”, “Zulu Finished”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-dial-one:66] Set(“Local/203@from-queue-0001bc2d;2”, “_zuludialopts=”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-dial-one:67] AGI(“Local/203@from-queue-0001bc2d;2”, “zulureplace-one.agi,DSTRING,90”) in new stack
[2019-02-25 10:00:41] VERBOSE[27514][C-0001651d] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/zulureplace-one.agi
[2019-02-25 10:00:42] VERBOSE[27514][C-0001651d] res_agi.c: zulureplace-one.agi,DSTRING,90: DIALSTRING: PJSIP/203/sip:[email protected]:5060
[2019-02-25 10:00:42] VERBOSE[27514][C-0001651d] res_agi.c: zulureplace-one.agi,DSTRING,90: DIALOPTS: HhtrM(auto-blkvm)I
[2019-02-25 10:00:42] VERBOSE[27514][C-0001651d] res_agi.c: zulureplace-one.agi,DSTRING,90: TIMEOUT: 15
[2019-02-25 10:00:42] VERBOSE[27514][C-0001651d] res_agi.c: zulureplace-one.agi,DSTRING,90: NEWDIALSTRING: PJSIP/203/sip:[email protected]:5060
[2019-02-25 10:00:42] VERBOSE[27514][C-0001651d] res_agi.c: <Local/203@from-queue-0001bc2d;2>AGI Script zulureplace-one.agi completed, returning 0
[2019-02-25 10:00:42] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-dial-one:68] NoOp(“Local/203@from-queue-0001bc2d;2”, “”) in new stack
[2019-02-25 10:00:42] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-dial-one:69] ExecIf(“Local/203@from-queue-0001bc2d;2”, “0?Set(D_OPTIONS=HhtrM(auto-blkvm)Ig)”) in new stack
[2019-02-25 10:00:42] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-dial-one:70] Dial(“Local/203@from-queue-0001bc2d;2”, “PJSIP/203/sip:[email protected]:5060,15,HhtrM(auto-blkvm)Ib(func-apply-sipheaders^s^1)”) in new stack
[2019-02-25 10:00:42] VERBOSE[27514][C-0001651d] app_stack.c: PJSIP/203-0003973a Internal Gosub(func-apply-sipheaders,s,1) start
[2019-02-25 10:00:42] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf(“PJSIP/203-0003973a”, “1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)”) in new stack
[2019-02-25 10:00:42] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@func-apply-sipheaders:2] UserEvent(“PJSIP/203-0003973a”, “zulu-call-b,type:func-apply-sipheaders,to:203,from:OBFUSCATED”) in new stack
[2019-02-25 10:00:42] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@func-apply-sipheaders:3] NoOp(“PJSIP/203-0003973a”, “Applying SIP Headers to channel”) in new stack
[2019-02-25 10:00:42] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@func-apply-sipheaders:4] Set(“PJSIP/203-0003973a”, “SIPHEADERKEYS=”) in new stack
[2019-02-25 10:00:42] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@func-apply-sipheaders:5] ExecIf(“PJSIP/203-0003973a”, “0?Set(Rheader=1)”) in new stack
[2019-02-25 10:00:42] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@func-apply-sipheaders:6] While(“PJSIP/203-0003973a”, “0”) in new stack
[2019-02-25 10:00:42] VERBOSE[27514][C-0001651d] app_while.c: Jumping to priority 10
[2019-02-25 10:00:42] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@func-apply-sipheaders:11] ExecIf(“PJSIP/203-0003973a”, “0?SIPRemoveHeader(Alert-Info:)”) in new stack
[2019-02-25 10:00:42] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@func-apply-sipheaders:12] ExecIf(“PJSIP/203-0003973a”, “0?Set(PJSIP_HEADER(remove,Alert-Info)=)”) in new stack
[2019-02-25 10:00:42] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@func-apply-sipheaders:13] Return(“PJSIP/203-0003973a”, “”) in new stack
[2019-02-25 10:00:42] VERBOSE[27514][C-0001651d] app_stack.c: Spawn extension (from-internal, 203, 1) exited non-zero on ‘PJSIP/203-0003973a’
[2019-02-25 10:00:42] VERBOSE[27514][C-0001651d] app_stack.c: PJSIP/203-0003973a Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2019-02-25 10:00:42] VERBOSE[27514][C-0001651d] app_dial.c: Called PJSIP/203/sip:[email protected]:5060
[2019-02-25 10:00:42] VERBOSE[27209][C-0001651d] app_queue.c: Local/203@from-queue-0001bc2d;1 is ringing
[2019-02-25 10:00:42] VERBOSE[26292] netsock2.c: Using SIP RTP Audio TOS bits 184
[2019-02-25 10:00:42] VERBOSE[26292] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field.
[2019-02-25 10:00:42] VERBOSE[26292] netsock2.c: Using SIP RTP Audio CoS mark 5
[2019-02-25 10:00:42] VERBOSE[27514][C-0001651d] app_dial.c: Connected line update to Local/203@from-queue-0001bc2d;2 prevented.
[2019-02-25 10:00:42] VERBOSE[27514][C-0001651d] app_dial.c: PJSIP/203-0003973a is ringing
[2019-02-25 10:00:42] VERBOSE[27514][C-0001651d] app_dial.c: PJSIP/203-0003973a is ringing
[2019-02-25 10:00:42] VERBOSE[27209][C-0001651d] app_queue.c: Local/203@from-queue-0001bc2d;1 is ringing
[2019-02-25 10:00:45] VERBOSE[27209][C-0001651d] app_queue.c: Nobody picked up in 19000 ms
[2019-02-25 10:00:45] VERBOSE[27209][C-0001651d] app_queue.c: – LazyMembers debugging - Numbusies: 0, Nummems: 2
[2019-02-25 10:00:45] VERBOSE[27514][C-0001651d] app_stack.c: PJSIP/203-0003973a Internal Gosub(crm-hangup,s,1) start
[2019-02-25 10:00:45] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@crm-hangup:1] NoOp(“PJSIP/203-0003973a”, “Sending Hangup to CRM”) in new stack
[2019-02-25 10:00:45] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@crm-hangup:2] NoOp(“PJSIP/203-0003973a”, “HANGUP CAUSE: 0”) in new stack
[2019-02-25 10:00:45] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@crm-hangup:3] ExecIf(“PJSIP/203-0003973a”, “0?Set(__CRM_VOICEMAIL=)”) in new stack
[2019-02-25 10:00:45] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@crm-hangup:4] NoOp(“PJSIP/203-0003973a”, “MASTER CHANNEL: 1551117642.541458 = 1551117592.541446”) in new stack
[2019-02-25 10:00:45] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@crm-hangup:5] GotoIf(“PJSIP/203-0003973a”, “1?return”) in new stack
[2019-02-25 10:00:45] VERBOSE[27514][C-0001651d] pbx_builtins.c: Goto (crm-hangup,s,8)
[2019-02-25 10:00:45] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@crm-hangup:8] Return(“PJSIP/203-0003973a”, “”) in new stack
[2019-02-25 10:00:45] VERBOSE[27514][C-0001651d] app_stack.c: Spawn extension (from-internal, 203, 1) exited non-zero on ‘PJSIP/203-0003973a’
[2019-02-25 10:00:45] VERBOSE[27514][C-0001651d] app_stack.c: PJSIP/203-0003973a Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
[2019-02-25 10:00:45] VERBOSE[27514][C-0001651d] app_macro.c: Spawn extension (macro-dial-one, s, 70) exited non-zero on ‘Local/203@from-queue-0001bc2d;2’ in macro ‘dial-one’
[2019-02-25 10:00:45] VERBOSE[27514][C-0001651d] app_macro.c: Spawn extension (macro-exten-vm, s, 14) exited non-zero on ‘Local/203@from-queue-0001bc2d;2’ in macro ‘exten-vm’
[2019-02-25 10:00:45] VERBOSE[27514][C-0001651d] pbx.c: Spawn extension (ext-local, 203, 2) exited non-zero on ‘Local/203@from-queue-0001bc2d;2’
[2019-02-25 10:00:45] VERBOSE[27514][C-0001651d] pbx.c: Executing [h@ext-local:1] Macro(“Local/203@from-queue-0001bc2d;2”, “hangupcall,”) in new stack
[2019-02-25 10:00:45] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-hangupcall:1] GotoIf(“Local/203@from-queue-0001bc2d;2”, “1?theend”) in new stack
[2019-02-25 10:00:45] VERBOSE[27514][C-0001651d] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2019-02-25 10:00:45] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-hangupcall:3] ExecIf(“Local/203@from-queue-0001bc2d;2”, “0?Set(CDR(recordingfile)=)”) in new stack
[2019-02-25 10:00:45] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-hangupcall:4] NoOp(“Local/203@from-queue-0001bc2d;2”, "PJSIP/203-0003973a monior file= ") in new stack
[2019-02-25 10:00:45] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-hangupcall:5] AGI(“Local/203@from-queue-0001bc2d;2”, “attendedtransfer-rec-restart.php,PJSIP/203-0003973a,”) in new stack
[2019-02-25 10:00:45] VERBOSE[27514][C-0001651d] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/attendedtransfer-rec-restart.php
[2019-02-25 10:00:45] VERBOSE[27514][C-0001651d] res_agi.c: <Local/203@from-queue-0001bc2d;2>AGI Script attendedtransfer-rec-restart.php completed, returning 0
[2019-02-25 10:00:45] VERBOSE[27514][C-0001651d] pbx.c: Executing [s@macro-hangupcall:6] Hangup(“Local/203@from-queue-0001bc2d;2”, “”) in new stack
[2019-02-25 10:00:45] VERBOSE[27514][C-0001651d] app_macro.c: Spawn extension (macro-hangupcall, s, 6) exited non-zero on ‘Local/203@from-queue-0001bc2d;2’ in macro ‘hangupcall’
[2019-02-25 10:00:45] VERBOSE[27514][C-0001651d] pbx.c: Spawn extension (ext-local, h, 1) exited non-zero on ‘Local/203@from-queue-0001bc2d;2’
[2019-02-25 10:00:46] VERBOSE[26816][C-00016519] bridge_channel.c: Channel PJSIP/202-00039732 left ‘simple_bridge’ basic-bridge
[2019-02-25 10:00:46] VERBOSE[26802][C-00016519] bridge_channel.c: Channel Local/604@from-queue-0001bc2a;2 left ‘simple_bridge’ basic-bridge
[2019-02-25 10:00:46] VERBOSE[26816][C-00016519] app_stack.c: PJSIP/202-00039732 Internal Gosub(crm-hangup,s,1) start
[2019-02-25 10:00:46] VERBOSE[26816][C-00016519] pbx.c: Executing [s@crm-hangup:1] NoOp(“PJSIP/202-00039732”, “Sending Hangup to CRM”) in new stack
[2019-02-25 10:00:46] VERBOSE[26816][C-00016519] pbx.c: Executing [s@crm-hangup:2] NoOp(“PJSIP/202-00039732”, “HANGUP CAUSE: 16”) in new stack
[2019-02-25 10:00:46] VERBOSE[26816][C-00016519] pbx.c: Executing [s@crm-hangup:3] ExecIf(“PJSIP/202-00039732”, “0?Set(__CRM_VOICEMAIL=)”) in new stack
[2019-02-25 10:00:46] VERBOSE[26816][C-00016519] pbx.c: Executing [s@crm-hangup:4] NoOp(“PJSIP/202-00039732”, “MASTER CHANNEL: 1551117502.541440 = 1551117455.541429”) in new stack
[2019-02-25 10:00:46] VERBOSE[26802][C-00016519] app_macro.c: Spawn extension (macro-dial, s, 40) exited non-zero on ‘Local/604@from-queue-0001bc2a;2’ in macro ‘dial’
[2019-02-25 10:00:46] VERBOSE[26802][C-00016519] pbx.c: Spawn extension (from-internal, 604, 19) exited non-zero on ‘Local/604@from-queue-0001bc2a;2’
[2019-02-25 10:00:46] VERBOSE[26802][C-00016519] pbx.c: Executing [h@from-internal:1] Macro(“Local/604@from-queue-0001bc2a;2”, “hangupcall”) in new stack
[2019-02-25 10:00:46] VERBOSE[26802][C-00016519] pbx.c: Executing [s@macro-hangupcall:1] GotoIf(“Local/604@from-queue-0001bc2a;2”, “1?theend”) in new stack
[2019-02-25 10:00:46] VERBOSE[26802][C-00016519] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2019-02-25 10:00:46] VERBOSE[26802][C-00016519] pbx.c: Executing [s@macro-hangupcall:3] ExecIf(“Local/604@from-queue-0001bc2a;2”, “0?Set(CDR(recordingfile)=)”) in new stack
[2019-02-25 10:00:46] VERBOSE[26816][C-00016519] pbx.c: Executing [s@crm-hangup:5] GotoIf(“PJSIP/202-00039732”, “1?return”) in new stack
[2019-02-25 10:00:46] VERBOSE[26816][C-00016519] pbx_builtins.c: Goto (crm-hangup,s,8)
[2019-02-25 10:00:46] VERBOSE[26816][C-00016519] pbx.c: Executing [s@crm-hangup:8] Return(“PJSIP/202-00039732”, “”) in new stack
[2019-02-25 10:00:46] VERBOSE[26816][C-00016519] app_stack.c: Spawn extension (macro-dial, s, 1) exited non-zero on ‘PJSIP/202-00039732’
[2019-02-25 10:00:46] VERBOSE[26816][C-00016519] app_stack.c: PJSIP/202-00039732 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
[2019-02-25 10:00:46] VERBOSE[26802][C-00016519] pbx.c: Executing [s@macro-hangupcall:4] NoOp(“Local/604@from-queue-0001bc2a;2”, "PJSIP/224-00039733 monior file= ") in new stack
[2019-02-25 10:00:46] VERBOSE[26802][C-00016519] pbx.c: Executing [s@macro-hangupcall:5] AGI(“Local/604@from-queue-0001bc2a;2”, “attendedtransfer-rec-restart.php,PJSIP/224-00039733,”) in new stack
[2019-02-25 10:00:46] VERBOSE[26802][C-00016519] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/attendedtransfer-rec-restart.php
[2019-02-25 10:00:46] VERBOSE[26802][C-00016519] res_agi.c: <Local/604@from-queue-0001bc2a;2>AGI Script attendedtransfer-rec-restart.php completed, returning 0
[2019-02-25 10:00:46] VERBOSE[26802][C-00016519] pbx.c: Executing [s@macro-hangupcall:6] Hangup(“Local/604@from-queue-0001bc2a;2”, “”) in new stack
[2019-02-25 10:00:46] VERBOSE[26802][C-00016519] app_macro.c: Spawn extension (macro-hangupcall, s, 6) exited non-zero on ‘Local/604@from-queue-0001bc2a;2’ in macro ‘hangupcall’
[2019-02-25 10:00:46] VERBOSE[26802][C-00016519] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on ‘Local/604@from-queue-0001bc2a;2’

7

[2019-02-25 10:00:46] VERBOSE[26817][C-00016519] bridge_channel.c: Channel Local/604@from-queue-0001bc2a;1 left ‘simple_bridge’ basic-bridge <92f2de54-e713-43dd-a707-4ce56d16d391>
[2019-02-25 10:00:46] VERBOSE[26588][C-00016519] bridge_channel.c: Channel SIP/fpbx-1-wW28Oy1XkZCv-000120e7 left ‘simple_bridge’ basic-bridge <92f2de54-e713-43dd-a707-4ce56d16d391>
[2019-02-25 10:00:46] VERBOSE[26588][C-00016519] pbx.c: Spawn extension (ext-queues, 100, 62) exited non-zero on ‘SIP/fpbx-1-wW28Oy1XkZCv-000120e7’
[2019-02-25 10:00:46] VERBOSE[26588][C-00016519] pbx.c: Executing [h@ext-queues:1] Macro(“SIP/fpbx-1-wW28Oy1XkZCv-000120e7”, “hangupcall,”) in new stack
[2019-02-25 10:00:46] VERBOSE[26588][C-00016519] pbx.c: Executing [s@macro-hangupcall:1] GotoIf(“SIP/fpbx-1-wW28Oy1XkZCv-000120e7”, “1?theend”) in new stack
[2019-02-25 10:00:46] VERBOSE[26588][C-00016519] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2019-02-25 10:00:46] VERBOSE[26588][C-00016519] pbx.c: Executing [s@macro-hangupcall:3] ExecIf(“SIP/fpbx-1-wW28Oy1XkZCv-000120e7”, “0?Set(CDR(recordingfile)=)”) in new stack
[2019-02-25 10:00:46] VERBOSE[26588][C-00016519] pbx.c: Executing [s@macro-hangupcall:4] NoOp(“SIP/fpbx-1-wW28Oy1XkZCv-000120e7”, "Local/604@from-queue-0001bc2a;1 monior file= ") in new stack
[2019-02-25 10:00:46] VERBOSE[26588][C-00016519] pbx.c: Executing [s@macro-hangupcall:5] AGI(“SIP/fpbx-1-wW28Oy1XkZCv-000120e7”, “attendedtransfer-rec-restart.php,Local/604@from-queue-0001bc2a;1,”) in new stack
[2019-02-25 10:00:46] VERBOSE[26588][C-00016519] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/attendedtransfer-rec-restart.php
[2019-02-25 10:00:46] VERBOSE[26588][C-00016519] res_agi.c: <SIP/fpbx-1-wW28Oy1XkZCv-000120e7>AGI Script attendedtransfer-rec-restart.php completed, returning 0
[2019-02-25 10:00:46] VERBOSE[26588][C-00016519] pbx.c: Executing [s@macro-hangupcall:6] Hangup(“SIP/fpbx-1-wW28Oy1XkZCv-000120e7”, “”) in new stack
[2019-02-25 10:00:46] VERBOSE[26588][C-00016519] app_macro.c: Spawn extension (macro-hangupcall, s, 6) exited non-zero on ‘SIP/fpbx-1-wW28Oy1XkZCv-000120e7’ in macro ‘hangupcall’
[2019-02-25 10:00:46] VERBOSE[26588][C-00016519] pbx.c: Spawn extension (ext-queues, h, 1) exited non-zero on ‘SIP/fpbx-1-wW28Oy1XkZCv-000120e7’
[2019-02-25 10:00:46] VERBOSE[26588][C-00016519] app_stack.c: SIP/fpbx-1-wW28Oy1XkZCv-000120e7 Internal Gosub(crm-hangup,s,1) start
[2019-02-25 10:00:46] VERBOSE[26588][C-00016519] pbx.c: Executing [s@crm-hangup:1] NoOp(“SIP/fpbx-1-wW28Oy1XkZCv-000120e7”, “Sending Hangup to CRM”) in new stack
[2019-02-25 10:00:46] VERBOSE[26588][C-00016519] pbx.c: Executing [s@crm-hangup:2] NoOp(“SIP/fpbx-1-wW28Oy1XkZCv-000120e7”, “HANGUP CAUSE: 16”) in new stack
[2019-02-25 10:00:46] VERBOSE[26588][C-00016519] pbx.c: Executing [s@crm-hangup:3] ExecIf(“SIP/fpbx-1-wW28Oy1XkZCv-000120e7”, “0?Set(__CRM_VOICEMAIL=)”) in new stack
[2019-02-25 10:00:46] VERBOSE[26588][C-00016519] pbx.c: Executing [s@crm-hangup:4] NoOp(“SIP/fpbx-1-wW28Oy1XkZCv-000120e7”, “MASTER CHANNEL: 1551117455.541429 = 1551117455.541429”) in new stack
[2019-02-25 10:00:46] VERBOSE[26588][C-00016519] pbx.c: Executing [s@crm-hangup:5] GotoIf(“SIP/fpbx-1-wW28Oy1XkZCv-000120e7”, “0?return”) in new stack
[2019-02-25 10:00:46] VERBOSE[26588][C-00016519] pbx.c: Executing [s@crm-hangup:6] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120e7”, “__CRM_HANGUP=1”) in new stack
[2019-02-25 10:00:46] VERBOSE[26588][C-00016519] pbx.c: Executing [s@crm-hangup:7] AGI(“SIP/fpbx-1-wW28Oy1XkZCv-000120e7”, “sangomacrm.agi”) in new stack
[2019-02-25 10:00:46] VERBOSE[26588][C-00016519] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
[2019-02-25 10:00:47] VERBOSE[26588][C-00016519] res_agi.c: sangomacrm.agi: LINKEDID: 1551117455.541429
[2019-02-25 10:00:47] VERBOSE[26588][C-00016519] res_agi.c: sangomacrm.agi: SOURCE: 15414906167
[2019-02-25 10:00:47] VERBOSE[26588][C-00016519] res_agi.c: sangomacrm.agi: DESTINATION:
[2019-02-25 10:00:47] VERBOSE[26588][C-00016519] res_agi.c: sangomacrm.agi: DIRECTION: INBOUND
[2019-02-25 10:00:47] VERBOSE[26588][C-00016519] res_agi.c: sangomacrm.agi: EXTTOCALL:
[2019-02-25 10:00:47] VERBOSE[26588][C-00016519] res_agi.c: sangomacrm.agi: START
[2019-02-25 10:00:47] VERBOSE[26588][C-00016519] res_agi.c: sangomacrm.agi: SCRIPT: php /var/www/html/admin/modules/sangomacrm/importOne.php ‘eyJ1dWlkIjoiMTU1MTExNzQ1NS41NDE0MjkiLCJzb3VyY2UiOiIxNTQxNDkwNjE2NyIsImRlc3RpbmF0aW9uIjoiIiwiZGlyZWN0aW9uIjoiSU5CT1VORCIsInR5cGUiOiJFTkQiLCJ6dWx1X3R5cGUiOiIiLCJleHR0b2NhbGwiOiIiLCJjbnVtIjoiMTU0MTQ5MDYxNjciLCJjbmFtIjoiS0lNIFlBU1VJIiwiY2FsbHBvcCI6ZmFsc2UsInZvaWNlbWFpbCI6IiJ9’ > /dev/null 2>&1 &
[2019-02-25 10:00:47] VERBOSE[26588][C-00016519] res_agi.c: <SIP/fpbx-1-wW28Oy1XkZCv-000120e7>AGI Script sangomacrm.agi completed, returning 0
[2019-02-25 10:00:47] VERBOSE[26588][C-00016519] pbx.c: Executing [s@crm-hangup:8] Return(“SIP/fpbx-1-wW28Oy1XkZCv-000120e7”, “”) in new stack
[2019-02-25 10:00:47] VERBOSE[26588][C-00016519] app_stack.c: Spawn extension (ext-queues, h, 1) exited non-zero on ‘SIP/fpbx-1-wW28Oy1XkZCv-000120e7’
[2019-02-25 10:00:47] VERBOSE[26588][C-00016519] app_stack.c: SIP/fpbx-1-wW28Oy1XkZCv-000120e7 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
[2019-02-25 10:00:50] VERBOSE[27209][C-0001651d] app_queue.c: Called Local/204@from-queue/n
[2019-02-25 10:00:50] VERBOSE[27209][C-0001651d] app_queue.c: – LazyMembers debugging - Numbusies: 0, Nummems: 2
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [204@from-queue:1] Set(“Local/204@from-queue-0001bc2e;2”, “QAGENT=204”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [204@from-queue:2] Set(“Local/204@from-queue-0001bc2e;2”, “__FROMQ=true”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [204@from-queue:3] GotoIf(“Local/204@from-queue-0001bc2e;2”, “0?hangup”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [204@from-queue:4] GotoIf(“Local/204@from-queue-0001bc2e;2”, “1?102,1:hangup”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx_builtins.c: Goto (from-queue,102,1)
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [102@from-queue:1] Goto(“Local/204@from-queue-0001bc2e;2”, “from-internal,204,1”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx_builtins.c: Goto (from-internal,204,1)
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [204@from-internal:1] GotoIf(“Local/204@from-queue-0001bc2e;2”, “1?ext-local,204,1:followme-check,204,1”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx_builtins.c: Goto (ext-local,204,1)
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [204@ext-local:1] Set(“Local/204@from-queue-0001bc2e;2”, “__RINGTIMER=15”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [204@ext-local:2] Macro(“Local/204@from-queue-0001bc2e;2”, “exten-vm,204,204,0,0,0”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-exten-vm:1] Macro(“Local/204@from-queue-0001bc2e;2”, “user-callerid,”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-user-callerid:1] Set(“Local/204@from-queue-0001bc2e;2”, “TOUCH_MONITOR=1551117650.541460”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-user-callerid:2] Set(“Local/204@from-queue-0001bc2e;2”, “AMPUSER=OBFUSCATED”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-user-callerid:3] GotoIf(“Local/204@from-queue-0001bc2e;2”, “1?report”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx_builtins.c: Goto (macro-user-callerid,s,16)
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-user-callerid:16] NoOp(“Local/204@from-queue-0001bc2e;2”, “Macro Depth is 2”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-user-callerid:17] GotoIf(“Local/204@from-queue-0001bc2e;2”, “1?report2:macroerror”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx_builtins.c: Goto (macro-user-callerid,s,18)
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-user-callerid:18] GotoIf(“Local/204@from-queue-0001bc2e;2”, “0?continue”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-user-callerid:19] ExecIf(“Local/204@from-queue-0001bc2e;2”, “1?Set(__CALLEE_ACCOUNCODE=)”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-user-callerid:20] Set(“Local/204@from-queue-0001bc2e;2”, “__TTL=63”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-user-callerid:21] GotoIf(“Local/204@from-queue-0001bc2e;2”, “1?continue”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx_builtins.c: Goto (macro-user-callerid,s,37)
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-user-callerid:37] Set(“Local/204@from-queue-0001bc2e;2”, “CALLERID(number)=OBFUSCATED”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-user-callerid:38] Set(“Local/204@from-queue-0001bc2e;2”, “CALLERID(name)=OBFUSCATED”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-user-callerid:39] GotoIf(“Local/204@from-queue-0001bc2e;2”, “0?cnum”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-user-callerid:40] Set(“Local/204@from-queue-0001bc2e;2”, “CDR(cnam)=OBFUSCATED”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-user-callerid:41] Set(“Local/204@from-queue-0001bc2e;2”, “CDR(cnum)=OBFUSCATED”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-user-callerid:42] Set(“Local/204@from-queue-0001bc2e;2”, “CHANNEL(language)=en”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-user-callerid:43] ExecIf(“Local/204@from-queue-0001bc2e;2”, “0?Set(CALLERID(name)=)”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-user-callerid:44] GosubIf(“Local/204@from-queue-0001bc2e;2”, “0?app-check-classofservce,s,1()”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-exten-vm:2] Set(“Local/204@from-queue-0001bc2e;2”, “RingGroupMethod=none”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-exten-vm:3] Set(“Local/204@from-queue-0001bc2e;2”, “__EXTTOCALL=204”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-exten-vm:4] Set(“Local/204@from-queue-0001bc2e;2”, “__PICKUPMARK=204”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-exten-vm:5] Set(“Local/204@from-queue-0001bc2e;2”, “RT=15”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-exten-vm:6] Gosub(“Local/204@from-queue-0001bc2e;2”, “sub-record-check,s,1(exten,204,dontcare)”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@sub-record-check:1] GotoIf(“Local/204@from-queue-0001bc2e;2”, “0?initialized”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@sub-record-check:2] Set(“Local/204@from-queue-0001bc2e;2”, “__REC_STATUS=INITIALIZED”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@sub-record-check:3] Set(“Local/204@from-queue-0001bc2e;2”, “NOW=1551117650”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@sub-record-check:4] Set(“Local/204@from-queue-0001bc2e;2”, “__DAY=25”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@sub-record-check:5] Set(“Local/204@from-queue-0001bc2e;2”, “__MONTH=02”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@sub-record-check:6] Set(“Local/204@from-queue-0001bc2e;2”, “__YEAR=2019”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@sub-record-check:7] Set(“Local/204@from-queue-0001bc2e;2”, “__TIMESTR=20190225-100050”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@sub-record-check:8] Set(“Local/204@from-queue-0001bc2e;2”, “__FROMEXTEN=OBFUSCATED”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@sub-record-check:9] Set(“Local/204@from-queue-0001bc2e;2”, “__MON_FMT=wav”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@sub-record-check:10] NoOp(“Local/204@from-queue-0001bc2e;2”, “Recordings initialized”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@sub-record-check:11] ExecIf(“Local/204@from-queue-0001bc2e;2”, “0?Set(ARG3=dontcare)”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@sub-record-check:12] Set(“Local/204@from-queue-0001bc2e;2”, “REC_POLICY_MODE_SAVE=”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@sub-record-check:13] ExecIf(“Local/204@from-queue-0001bc2e;2”, “0?Set(REC_STATUS=NO)”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@sub-record-check:14] GotoIf(“Local/204@from-queue-0001bc2e;2”, “5?checkaction”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx_builtins.c: Goto (sub-record-check,s,17)
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@sub-record-check:17] GotoIf(“Local/204@from-queue-0001bc2e;2”, “1?sub-record-check,exten,1”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx_builtins.c: Goto (sub-record-check,exten,1)
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [exten@sub-record-check:1] NoOp(“Local/204@from-queue-0001bc2e;2”, “Exten Recording Check between OBFUSCATED and 204”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [exten@sub-record-check:2] Set(“Local/204@from-queue-0001bc2e;2”, “CALLTYPE=external”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [exten@sub-record-check:3] ExecIf(“Local/204@from-queue-0001bc2e;2”, “0?Set(CALLTYPE=)”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [exten@sub-record-check:4] Set(“Local/204@from-queue-0001bc2e;2”, “CALLEE=dontcare”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [exten@sub-record-check:5] ExecIf(“Local/204@from-queue-0001bc2e;2”, “0?Set(CALLEE=dontcare)”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [exten@sub-record-check:6] GotoIf(“Local/204@from-queue-0001bc2e;2”, “1?callee”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx_builtins.c: Goto (sub-record-check,exten,11)
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [exten@sub-record-check:11] Gosub(“Local/204@from-queue-0001bc2e;2”, “recordcheck,1(dontcare,external,204)”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp(“Local/204@from-queue-0001bc2e;2”, “Starting recording check against dontcare”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [recordcheck@sub-record-check:2] Goto(“Local/204@from-queue-0001bc2e;2”, “dontcare”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [recordcheck@sub-record-check:3] Return(“Local/204@from-queue-0001bc2e;2”, “”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [exten@sub-record-check:12] Return(“Local/204@from-queue-0001bc2e;2”, “”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-exten-vm:7] GotoIf(“Local/204@from-queue-0001bc2e;2”, “1?macrodial”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx_builtins.c: Goto (macro-exten-vm,s,13)
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-exten-vm:13] GosubIf(“Local/204@from-queue-0001bc2e;2”, “0?clrheader,1()”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-exten-vm:14] Macro(“Local/204@from-queue-0001bc2e;2”, “dial-one,15,HhTtrM(auto-blkvm),204”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-dial-one:1] Set(“Local/204@from-queue-0001bc2e;2”, “DEXTEN=204”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-dial-one:2] Set(“Local/204@from-queue-0001bc2e;2”, “__CRM_SOURCE=OBFUSCATED”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-dial-one:3] UserEvent(“Local/204@from-queue-0001bc2e;2”, “zulu-inbound-call,type:macro-dial-one,from:OBFUSCATED,to:204”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-dial-one:4] ExecIf(“Local/204@from-queue-0001bc2e;2”, “0?Set(__EXTTOCALL=204)”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-dial-one:5] Set(“Local/204@from-queue-0001bc2e;2”, “DIALSTATUS_CW=”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-dial-one:6] GosubIf(“Local/204@from-queue-0001bc2e;2”, “0?screen,1()”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-dial-one:7] GosubIf(“Local/204@from-queue-0001bc2e;2”, “0?cf,1()”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-dial-one:8] GotoIf(“Local/204@from-queue-0001bc2e;2”, “1?skip1”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx_builtins.c: Goto (macro-dial-one,s,11)
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-dial-one:11] GotoIf(“Local/204@from-queue-0001bc2e;2”, “0?nodial”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-dial-one:12] GotoIf(“Local/204@from-queue-0001bc2e;2”, “0?continue”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-dial-one:13] Set(“Local/204@from-queue-0001bc2e;2”, “EXTHASCW=”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-dial-one:14] GotoIf(“Local/204@from-queue-0001bc2e;2”, “1?next1:cwinusebusy”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx_builtins.c: Goto (macro-dial-one,s,15)
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-dial-one:15] GotoIf(“Local/204@from-queue-0001bc2e;2”, “0?docfu:skip3”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx_builtins.c: Goto (macro-dial-one,s,19)
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-dial-one:19] GotoIf(“Local/204@from-queue-0001bc2e;2”, “1?next2:continue”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx_builtins.c: Goto (macro-dial-one,s,20)
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-dial-one:20] GotoIf(“Local/204@from-queue-0001bc2e;2”, “1?continue”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx_builtins.c: Goto (macro-dial-one,s,28)
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-dial-one:28] GotoIf(“Local/204@from-queue-0001bc2e;2”, “0?nodial”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-dial-one:29] GosubIf(“Local/204@from-queue-0001bc2e;2”, “1?dstring,1():dlocal,1()”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:1] Set(“Local/204@from-queue-0001bc2e;2”, “DSTRING=”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:2] Set(“Local/204@from-queue-0001bc2e;2”, “DEVICES=204”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:3] ExecIf(“Local/204@from-queue-0001bc2e;2”, “0?Return()”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:4] ExecIf(“Local/204@from-queue-0001bc2e;2”, “0?Set(DEVICES=04)”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:5] Set(“Local/204@from-queue-0001bc2e;2”, “LOOPCNT=1”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:6] Set(“Local/204@from-queue-0001bc2e;2”, “ITER=1”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:7] Set(“Local/204@from-queue-0001bc2e;2”, “THISDIAL=PJSIP/204”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:8] GosubIf(“Local/204@from-queue-0001bc2e;2”, “1?zap2dahdi,1()”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [zap2dahdi@macro-dial-one:1] ExecIf(“Local/204@from-queue-0001bc2e;2”, “0?Return()”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [zap2dahdi@macro-dial-one:2] Set(“Local/204@from-queue-0001bc2e;2”, “NEWDIAL=”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [zap2dahdi@macro-dial-one:3] Set(“Local/204@from-queue-0001bc2e;2”, “LOOPCNT2=1”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [zap2dahdi@macro-dial-one:4] Set(“Local/204@from-queue-0001bc2e;2”, “ITER2=1”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [zap2dahdi@macro-dial-one:5] Set(“Local/204@from-queue-0001bc2e;2”, “THISPART2=PJSIP/204”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [zap2dahdi@macro-dial-one:6] ExecIf(“Local/204@from-queue-0001bc2e;2”, “0?Set(THISPART2=DAHDIIP/204)”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [zap2dahdi@macro-dial-one:7] Set(“Local/204@from-queue-0001bc2e;2”, “NEWDIAL=PJSIP/204&”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [zap2dahdi@macro-dial-one:8] Set(“Local/204@from-queue-0001bc2e;2”, “ITER2=2”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [zap2dahdi@macro-dial-one:9] GotoIf(“Local/204@from-queue-0001bc2e;2”, “0?begin2”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [zap2dahdi@macro-dial-one:10] Set(“Local/204@from-queue-0001bc2e;2”, “THISDIAL=PJSIP/204”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [zap2dahdi@macro-dial-one:11] Return(“Local/204@from-queue-0001bc2e;2”, “”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:9] GotoIf(“Local/204@from-queue-0001bc2e;2”, “0?docheck”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:10] NoOp(“Local/204@from-queue-0001bc2e;2”, “Debug: Found PJSIP Destination PJSIP/204”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:11] GotoIf(“Local/204@from-queue-0001bc2e;2”, “0?doset”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:12] NoOp(“Local/204@from-queue-0001bc2e;2”, “Debug: Updating PJSIP Destination with PJSIP_DIAL_CONTACTS”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:13] Set(“Local/204@from-queue-0001bc2e;2”, “THISDIAL=PJSIP/204/sip:[email protected]:5060”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:14] ExecIf(“Local/204@from-queue-0001bc2e;2”, “0?Set(DIALSTATUS=CHANUNAVAIL)”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:15] GotoIf(“Local/204@from-queue-0001bc2e;2”, “0?skipset”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:16] Set(“Local/204@from-queue-0001bc2e;2”, “DSTRING=PJSIP/204/sip:[email protected]:5060&”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:17] Set(“Local/204@from-queue-0001bc2e;2”, “ITER=2”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:18] GotoIf(“Local/204@from-queue-0001bc2e;2”, “0?begin”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:19] ExecIf(“Local/204@from-queue-0001bc2e;2”, “0?Return()”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:20] Set(“Local/204@from-queue-0001bc2e;2”, “DSTRING=PJSIP/204/sip:[email protected]:5060”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [dstring@macro-dial-one:21] Return(“Local/204@from-queue-0001bc2e;2”, “”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-dial-one:30] GotoIf(“Local/204@from-queue-0001bc2e;2”, “0?nodial”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-dial-one:31] GotoIf(“Local/204@from-queue-0001bc2e;2”, “0?skiptrace”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-dial-one:32] GosubIf(“Local/204@from-queue-0001bc2e;2”, “1?ctset,1():ctclear,1()”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [ctset@macro-dial-one:1] Set(“Local/204@from-queue-0001bc2e;2”, “DB(CALLTRACE/204)=OBFUSCATED”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [ctset@macro-dial-one:2] Return(“Local/204@from-queue-0001bc2e;2”, “”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-dial-one:33] Set(“Local/204@from-queue-0001bc2e;2”, “D_OPTIONS=HhTtrM(auto-blkvm)”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-dial-one:34] GosubIf(“Local/204@from-queue-0001bc2e;2”, “0?func-set-sipheader,s,1(Alert-Info,)”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-dial-one:35] NoOp(“Local/204@from-queue-0001bc2e;2”, "Blind Transfer: , Attended Transfer: , User: OBFUSCATED, Alert Info: ") in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-dial-one:36] ExecIf(“Local/204@from-queue-0001bc2e;2”, “1?Set(ALERT_INFO=)”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-dial-one:37] ExecIf(“Local/204@from-queue-0001bc2e;2”, “0?Set(ALERT_INFO=)”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-dial-one:38] ExecIf(“Local/204@from-queue-0001bc2e;2”, “0?Set(ALERT_INFO=)”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-dial-one:39] ExecIf(“Local/204@from-queue-0001bc2e;2”, “0?Set(ALERT_INFO=Normal;volume=)”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-dial-one:40] ExecIf(“Local/204@from-queue-0001bc2e;2”, “0?Set(ALERT_INFO=Normal;volume=)”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-dial-one:41] GosubIf(“Local/204@from-queue-0001bc2e;2”, “0?func-set-sipheader,s,1(Alert-Info,)”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-dial-one:42] ExecIf(“Local/204@from-queue-0001bc2e;2”, “0?Set(CHANNEL(musicclass)=)”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-dial-one:43] GosubIf(“Local/204@from-queue-0001bc2e;2”, “0?qwait,1()”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-dial-one:44] Set(“Local/204@from-queue-0001bc2e;2”, “__CWIGNORE=TRUE”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-dial-one:45] Set(“Local/204@from-queue-0001bc2e;2”, “__KEEPCID=TRUE”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-dial-one:46] GotoIf(“Local/204@from-queue-0001bc2e;2”, “0?usegoto,1”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-dial-one:47] GotoIf(“Local/204@from-queue-0001bc2e;2”, “1?godial”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx_builtins.c: Goto (macro-dial-one,s,52)
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-dial-one:52] Macro(“Local/204@from-queue-0001bc2e;2”, “dialout-one-predial-hook,”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-dialout-one-predial-hook:1] MacroExit(“Local/204@from-queue-0001bc2e;2”, “”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-dial-one:53] ExecIf(“Local/204@from-queue-0001bc2e;2”, “1?Set(D_OPTIONS=HhtrM(auto-blkvm)I)”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-dial-one:54] GotoIf(“Local/204@from-queue-0001bc2e;2”, “1?zulunourl”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx_builtins.c: Goto (macro-dial-one,s,56)
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-dial-one:56] ExecIf(“Local/204@from-queue-0001bc2e;2”, “0?Set(ZULU_TYPE=)”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-dial-one:57] GotoIf(“Local/204@from-queue-0001bc2e;2”, “1?skippopup”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx_builtins.c: Goto (macro-dial-one,s,64)
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-dial-one:64] UserEvent(“Local/204@from-queue-0001bc2e;2”, “zulu-call,eventtype:calling,extension:204,type:,url:,cnam:OBFUSCATED,cnum:OBFUSCATED,lid:1551117592.541446,from:OBFUSCATED,to:204,user:”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-dial-one:65] NoOp(“Local/204@from-queue-0001bc2e;2”, “Zulu Finished”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-dial-one:66] Set(“Local/204@from-queue-0001bc2e;2”, “_zuludialopts=”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-dial-one:67] AGI(“Local/204@from-queue-0001bc2e;2”, “zulureplace-one.agi,DSTRING,90”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/zulureplace-one.agi
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] res_agi.c: zulureplace-one.agi,DSTRING,90: DIALSTRING: PJSIP/204/sip:[email protected]:5060
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] res_agi.c: zulureplace-one.agi,DSTRING,90: DIALOPTS: HhtrM(auto-blkvm)I
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] res_agi.c: zulureplace-one.agi,DSTRING,90: TIMEOUT: 15
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] res_agi.c: zulureplace-one.agi,DSTRING,90: NEWDIALSTRING: PJSIP/204/sip:[email protected]:5060
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] res_agi.c: <Local/204@from-queue-0001bc2e;2>AGI Script zulureplace-one.agi completed, returning 0

8

[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-dial-one:68] NoOp(“Local/204@from-queue-0001bc2e;2”, “”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-dial-one:69] ExecIf(“Local/204@from-queue-0001bc2e;2”, “0?Set(D_OPTIONS=HhtrM(auto-blkvm)Ig)”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-dial-one:70] Dial(“Local/204@from-queue-0001bc2e;2”, “PJSIP/204/sip:[email protected]:5060,15,HhtrM(auto-blkvm)Ib(func-apply-sipheaders^s^1)”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] app_stack.c: PJSIP/204-0003973b Internal Gosub(func-apply-sipheaders,s,1) start
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf(“PJSIP/204-0003973b”, “1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@func-apply-sipheaders:2] UserEvent(“PJSIP/204-0003973b”, “zulu-call-b,type:func-apply-sipheaders,to:204,from:OBFUSCATED”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@func-apply-sipheaders:3] NoOp(“PJSIP/204-0003973b”, “Applying SIP Headers to channel”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@func-apply-sipheaders:4] Set(“PJSIP/204-0003973b”, “SIPHEADERKEYS=”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@func-apply-sipheaders:5] ExecIf(“PJSIP/204-0003973b”, “0?Set(Rheader=1)”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@func-apply-sipheaders:6] While(“PJSIP/204-0003973b”, “0”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] app_while.c: Jumping to priority 10
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@func-apply-sipheaders:11] ExecIf(“PJSIP/204-0003973b”, “0?SIPRemoveHeader(Alert-Info:)”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@func-apply-sipheaders:12] ExecIf(“PJSIP/204-0003973b”, “0?Set(PJSIP_HEADER(remove,Alert-Info)=)”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@func-apply-sipheaders:13] Return(“PJSIP/204-0003973b”, “”) in new stack
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] app_stack.c: Spawn extension (from-internal, 204, 1) exited non-zero on ‘PJSIP/204-0003973b’
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] app_stack.c: PJSIP/204-0003973b Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] app_dial.c: Called PJSIP/204/sip:[email protected]:5060
[2019-02-25 10:00:50] VERBOSE[27209][C-0001651d] app_queue.c: Local/204@from-queue-0001bc2e;1 is ringing
[2019-02-25 10:00:50] VERBOSE[12921] netsock2.c: Using SIP RTP Audio TOS bits 184
[2019-02-25 10:00:50] VERBOSE[12921] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field.
[2019-02-25 10:00:50] VERBOSE[12921] netsock2.c: Using SIP RTP Audio CoS mark 5
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] app_dial.c: Connected line update to Local/204@from-queue-0001bc2e;2 prevented.
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] app_dial.c: PJSIP/204-0003973b is ringing
[2019-02-25 10:00:50] VERBOSE[27555][C-0001651d] app_dial.c: PJSIP/204-0003973b is ringing
[2019-02-25 10:00:50] VERBOSE[27209][C-0001651d] app_queue.c: Local/204@from-queue-0001bc2e;1 is ringing
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] app_queue.c: Nobody picked up in 6000 ms
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] app_queue.c: – LazyMembers debugging - Numbusies: 0, Nummems: 2
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] res_musiconhold.c: Stopped music on hold on SIP/fpbx-1-wW28Oy1XkZCv-000120ec
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:62] Macro(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “blkvm-clr,”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-blkvm-clr:1] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “SHARED(BLKVM,SIP/fpbx-1-wW28Oy1XkZCv-000120ec)=”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-blkvm-clr:2] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “GOSUB_RETVAL=”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-blkvm-clr:3] MacroExit(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:63] Gosub(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “sub-record-cancel,s,1()”) in new stack
[2019-02-25 10:00:56] VERBOSE[27555][C-0001651d] app_stack.c: PJSIP/204-0003973b Internal Gosub(crm-hangup,s,1) start
[2019-02-25 10:00:56] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@crm-hangup:1] NoOp(“PJSIP/204-0003973b”, “Sending Hangup to CRM”) in new stack
[2019-02-25 10:00:56] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@crm-hangup:2] NoOp(“PJSIP/204-0003973b”, “HANGUP CAUSE: 0”) in new stack
[2019-02-25 10:00:56] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@crm-hangup:3] ExecIf(“PJSIP/204-0003973b”, “0?Set(__CRM_VOICEMAIL=)”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@sub-record-cancel:1] Return(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:64] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “__NODEST=”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:65] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “_QUEUE_PRIO=0”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:66] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “__CWIGNORE=”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:67] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “QDEST=”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:68] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “VQ_DEST=”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:69] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “_CALLER_DEST=”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:70] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “_AGENT_DEST=”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:71] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “QFULL_DEST=”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:72] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “VQ_FULL_DEST=”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:73] GotoIf(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “0?,”) in new stack
[2019-02-25 10:00:56] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@crm-hangup:4] NoOp(“PJSIP/204-0003973b”, “MASTER CHANNEL: 1551117650.541461 = 1551117592.541446”) in new stack
[2019-02-25 10:00:56] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@crm-hangup:5] GotoIf(“PJSIP/204-0003973b”, “1?return”) in new stack
[2019-02-25 10:00:56] VERBOSE[27555][C-0001651d] pbx_builtins.c: Goto (crm-hangup,s,8)
[2019-02-25 10:00:56] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@crm-hangup:8] Return(“PJSIP/204-0003973b”, “”) in new stack
[2019-02-25 10:00:56] VERBOSE[27555][C-0001651d] app_stack.c: Spawn extension (from-internal, 204, 1) exited non-zero on ‘PJSIP/204-0003973b’
[2019-02-25 10:00:56] VERBOSE[27555][C-0001651d] app_stack.c: PJSIP/204-0003973b Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
[2019-02-25 10:00:56] VERBOSE[27555][C-0001651d] app_macro.c: Spawn extension (macro-dial-one, s, 70) exited non-zero on ‘Local/204@from-queue-0001bc2e;2’ in macro ‘dial-one’
[2019-02-25 10:00:56] VERBOSE[27555][C-0001651d] app_macro.c: Spawn extension (macro-exten-vm, s, 14) exited non-zero on ‘Local/204@from-queue-0001bc2e;2’ in macro ‘exten-vm’
[2019-02-25 10:00:56] VERBOSE[27555][C-0001651d] pbx.c: Spawn extension (ext-local, 204, 2) exited non-zero on ‘Local/204@from-queue-0001bc2e;2’
[2019-02-25 10:00:56] VERBOSE[27555][C-0001651d] pbx.c: Executing [h@ext-local:1] Macro(“Local/204@from-queue-0001bc2e;2”, “hangupcall,”) in new stack
[2019-02-25 10:00:56] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-hangupcall:1] GotoIf(“Local/204@from-queue-0001bc2e;2”, “1?theend”) in new stack
[2019-02-25 10:00:56] VERBOSE[27555][C-0001651d] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:74] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “QJOINEMPTY_DEST=”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:75] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “VQ_JOINEMPTY_DEST=”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:76] GotoIf(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “0?,”) in new stack
[2019-02-25 10:00:56] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-hangupcall:3] ExecIf(“Local/204@from-queue-0001bc2e;2”, “0?Set(CDR(recordingfile)=)”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:77] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “QLEAVEEMPTY_DEST=”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:78] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “VQ_LEAVEEMPTY_DEST=”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:79] GotoIf(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “0?,”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:80] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “QJOINUNAVAIL_DEST=”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:81] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “VQ_JOINUNAVAIL_DEST=”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:82] GotoIf(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “0?,”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:83] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “QLEAVEUNAVAIL_DEST=”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:84] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “VQ_LEAVEUNAVAIL_DEST=”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:85] GotoIf(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “0?,”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [102@ext-queues:86] GotoIf(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “1?ext-local,vmu702,1:,”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx_builtins.c: Goto (ext-local,vmu702,1)
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [vmu702@ext-local:1] Macro(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “vm,702,NOANSWER,”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-vm:1] Macro(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “user-callerid,SKIPTTL”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-user-callerid:1] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “TOUCH_MONITOR=1551117592.541446”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-user-callerid:2] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “AMPUSER=OBFUSCATED”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-user-callerid:3] GotoIf(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “0?report”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-user-callerid:4] ExecIf(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “0?Set(REALCALLERIDNUM=OBFUSCATED)”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-user-callerid:5] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “AMPUSER=”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-user-callerid:6] GotoIf(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “0?limit”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-user-callerid:7] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “AMPUSERCIDNAME=”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-user-callerid:8] ExecIf(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “0?Set(__CIDMASQUERADING=TRUE)”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-user-callerid:9] GotoIf(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “1?report”) in new stack
[2019-02-25 10:00:56] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-hangupcall:4] NoOp(“Local/204@from-queue-0001bc2e;2”, "PJSIP/204-0003973b monior file= ") in new stack
[2019-02-25 10:00:56] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-hangupcall:5] AGI(“Local/204@from-queue-0001bc2e;2”, “attendedtransfer-rec-restart.php,PJSIP/204-0003973b,”) in new stack
[2019-02-25 10:00:56] VERBOSE[27555][C-0001651d] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/attendedtransfer-rec-restart.php
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx_builtins.c: Goto (macro-user-callerid,s,16)
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-user-callerid:16] NoOp(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “Macro Depth is 2”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-user-callerid:17] GotoIf(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “1?report2:macroerror”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx_builtins.c: Goto (macro-user-callerid,s,18)
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-user-callerid:18] GotoIf(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “1?continue”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx_builtins.c: Goto (macro-user-callerid,s,37)
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-user-callerid:37] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “CALLERID(number)=OBFUSCATED”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-user-callerid:38] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “CALLERID(name)=OBFUSCATED”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-user-callerid:39] GotoIf(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “0?cnum”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-user-callerid:40] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “CDR(cnam)=OBFUSCATED”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-user-callerid:41] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “CDR(cnum)=OBFUSCATED”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-user-callerid:42] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “CHANNEL(language)=en”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-user-callerid:43] ExecIf(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “0?Set(CALLERID(name)=)”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-user-callerid:44] GosubIf(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “0?app-check-classofservce,s,1()”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-vm:2] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “VMGAIN=”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-vm:3] Macro(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “blkvm-check,”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-blkvm-check:1] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “GOSUB_RETVAL=”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-blkvm-check:2] ExecIf(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “0?Set(GOSUB_RETVAL=TRUE)”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-blkvm-check:3] MacroExit(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-vm:4] GotoIf(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “1?vmx,1”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx_builtins.c: Goto (macro-vm,vmx,1)
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [vmx@macro-vm:1] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “__EXTTOCALL=702”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [vmx@macro-vm:2] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “__CRM_VOICEMAIL=702”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [vmx@macro-vm:3] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “MEXTEN=702”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [vmx@macro-vm:4] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “MMODE=NOANSWER”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [vmx@macro-vm:5] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “RETVM=”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [vmx@macro-vm:6] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “MODE=unavail”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [vmx@macro-vm:7] Macro(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “get-vmcontext,702”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-get-vmcontext:1] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “VMCONTEXT=default”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-get-vmcontext:2] GotoIf(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “0?200:300”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx_builtins.c: Goto (macro-get-vmcontext,s,300)
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-get-vmcontext:300] NoOp(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [vmx@macro-vm:8] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “MODE=unavail”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [vmx@macro-vm:9] NoOp(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “MODE IS: unavail”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [vmx@macro-vm:10] GotoIf(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “1?chknomsg”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx_builtins.c: Goto (macro-vm,vmx,12)
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [vmx@macro-vm:12] GotoIf(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “0?s-NOANSWER,1”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [vmx@macro-vm:13] GotoIf(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “1?notdirect”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx_builtins.c: Goto (macro-vm,vmx,15)
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [vmx@macro-vm:15] NoOp(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “Checking if ext 702 is enabled: blocked”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [vmx@macro-vm:16] GotoIf(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “1?s-NOANSWER,1”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx_builtins.c: Goto (macro-vm,s-NOANSWER,1)
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [s-NOANSWER@macro-vm:1] Macro(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “get-vmcontext,702”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-get-vmcontext:1] Set(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “VMCONTEXT=default”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-get-vmcontext:2] GotoIf(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “0?200:300”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx_builtins.c: Goto (macro-get-vmcontext,s,300)
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [s@macro-get-vmcontext:300] NoOp(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] pbx.c: Executing [s-NOANSWER@macro-vm:2] VoiceMail(“SIP/fpbx-1-wW28Oy1XkZCv-000120ec”, “702@default,u”) in new stack
[2019-02-25 10:00:56] VERBOSE[27209][C-0001651d] file.c: <SIP/fpbx-1-wW28Oy1XkZCv-000120ec> Playing ‘/var/spool/asterisk/voicemail/default/702/unavail.slin’ (language ‘en’)
[2019-02-25 10:00:56] VERBOSE[27555][C-0001651d] res_agi.c: <Local/204@from-queue-0001bc2e;2>AGI Script attendedtransfer-rec-restart.php completed, returning 0
[2019-02-25 10:00:56] VERBOSE[27555][C-0001651d] pbx.c: Executing [s@macro-hangupcall:6] Hangup(“Local/204@from-queue-0001bc2e;2”, “”) in new stack
[2019-02-25 10:00:56] VERBOSE[27555][C-0001651d] app_macro.c: Spawn extension (macro-hangupcall, s, 6) exited non-zero on ‘Local/204@from-queue-0001bc2e;2’ in macro ‘hangupcall’

{ END }

9

Your first extension was rang for 15 seconds, that is more than one ring. That is at least 2-3 rings.

Your second rang for 19 seconds so it has 1-2 more rings on it. Try setting the ring time one the other extension to match and see what happens.

10

it looks like your queue is timing out after 20 seconds.

11

Guys, thanks for looking. I see the same thing in the log about the second ext ringing, but the phone rings 1x and then routes back to first extension which rings 3-4 times faithfully. If I switch the order of extensions, the first phone still rings correctly. Is there somewhere else to look?

The queue settings are:
Max Wait: 1 min
Agent Timeout: 19 sec
Retry: 3 sec
Failover: voicemail

Ring Strategy: Linear

12

That’s not true. The system rang the phone for 15 seconds, I posted that piece of your log. Your phones have shorter time outs vs how long the queue wants to ring each agent.

Not seeing anything for this other agent that can get multiple rings. Are both agents Ring Time the same?

13

Both phones are configured with an Endpoint Mgr template and I have verified they both have their Ring Timeout set to default of 60sec on the phones (Sangoma S405). The queue is imposing a 19sec agent timeout. The retry is set to 3 sec.

Why they would “ring” with different lengths as displayed in the log I don’t know, but I can upload a video of extension 2 ringing 1x (ie 1 audible and visual ring) before failing back to extension 1; (204) in this case. I also suspect some other “linking” file to possibly be a culprit since if I switch ext 203 to position 1 and ext 204 to position 2, 203 will then ring the correct number of times, but ext 204 will ring just the 1x.

Does that make any sense to you folks?

14

Was this always the ring strategy for this queue, Or it was changed to linear?

15

When I first set it up, it was likely ringall (the default) because it was ringing out to a single external number. I switched to linear once they started using internal staff to handle this department. I have changed it in the interim from linear back to ringall until I find a fix to this issue.

That said, with ringall as the strategy, both phones ring the correct amount of times (roughly 2 sets of 4 rings) before sending on to vmail if unanswered.

16

That’s your answer.

There’s a known Asterisk issue, that if you change the ring strategy to linear it won’t function properly until you restart Asterisk.

The other way around would be to delete the queue and recreate it with linear set upon creating the Queue.

17

I would love to jump at this fix, but I did a couple reboots during the process. However, I can try again and see if that fixes it. So are you saying I should change from ringall to linear -> reboot -> verify?

Thanks for the tip!

18

Read it here: ASTERISK-17049: [patch] Setting linear queue strategy requires asterisk restart

19

No. Change to linear from ringall then fwconsole restart and verify. There is zero need to reboot.

20

Thanks Gentlemen! I’ll check out the link for more info, make necessary change, restart asterisk and update you all on the results.

Sincere Thanks!