OK - Output:
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] res_agi.c: dialparties.agi: Starting New Dialparties.agi
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] res_agi.c: dialparties.agi: Caller ID name is 'IDPR: WIRELESS CALLER' number is '719xxx00x8'
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] res_agi.c: dialparties.agi: Methodology of ring is 'ringall'
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] res_agi.c: dialparties.agi: Added extension 401 to extension map
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] res_agi.c: dialparties.agi: Added extension 403 to extension map
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] res_agi.c: dialparties.agi: Added extension 304 to extension map
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] res_agi.c: dialparties.agi: Added extension 305 to extension map
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] res_agi.c: dialparties.agi: Extension 401 cf is disabled
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] res_agi.c: dialparties.agi: Extension 403 cf is disabled
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] res_agi.c: dialparties.agi: Extension 304 cf is disabled
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] res_agi.c: dialparties.agi: Extension 305 cf is disabled
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] res_agi.c: dialparties.agi: Extension 401 do not disturb is disabled
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] res_agi.c: dialparties.agi: Extension 403 do not disturb is disabled
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] res_agi.c: dialparties.agi: Extension 304 do not disturb is disabled
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] res_agi.c: dialparties.agi: Extension 305 do not disturb is disabled
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] res_agi.c: dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE)
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] res_agi.c: dialparties.agi: Extension 401 has ExtensionState: 0
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] res_agi.c: dialparties.agi: Checking CW and CFB status for extension 401
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] res_agi.c: dialparties.agi: dbset CALLTRACE/401 to 719xxx0098
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] res_agi.c: dialparties.agi: dbset CALLTRACE/403 to 719xxx0098
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] res_agi.c: dialparties.agi: EXTENSION_STATE: 1 (INUSE)
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] res_agi.c: dialparties.agi: Extension 304 has ExtensionState: 1
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] res_agi.c: dialparties.agi: Checking CW and CFB status for extension 304
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] res_agi.c: dialparties.agi: Extension 304 is not available to be called
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] res_agi.c: dialparties.agi: Extension 304 has call waiting disabled
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] res_agi.c: dialparties.agi: EXTENSION_STATE: 1 (INUSE)
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] res_agi.c: dialparties.agi: Extension 305 has ExtensionState: 1
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] res_agi.c: dialparties.agi: Checking CW and CFB status for extension 305
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] res_agi.c: dialparties.agi: Extension 305 is not available to be called
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] res_agi.c: dialparties.agi: Extension 305 has call waiting disabled
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] res_agi.c: dialparties.agi: Filtered ARG3: 401-403
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] res_agi.c: <SIP/cbeyond-in-0000b5b5>AGI Script dialparties.agi completed, returning 0
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] pbx.c: Executing [s@macro-dial:9] NoOp("SIP/cbeyond-in-0000b5b5", "Returned from dialparties with groups to dial") in new stack
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] pbx.c: Executing [s@macro-dial:10] Set("SIP/cbeyond-in-0000b5b5", "LOOPCNT=2") in new stack
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] pbx.c: Executing [s@macro-dial:11] Set("SIP/cbeyond-in-0000b5b5", "ITER=1") in new stack
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] pbx.c: Executing [s@macro-dial:12] Set("SIP/cbeyond-in-0000b5b5", "EXTTOCALL=401") in new stack
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] pbx.c: Executing [s@macro-dial:13] NoOp("SIP/cbeyond-in-0000b5b5", "Working with 401") in new stack
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] pbx.c: Executing [s@macro-dial:14] ExecIf("SIP/cbeyond-in-0000b5b5", "0?Set(HASH(__SIPHEADERS,Alert-Info)=;volume=)") in new stack
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] pbx.c: Executing [s@macro-dial:15] ExecIf("SIP/cbeyond-in-0000b5b5", "0?Set(HASH(__SIPHEADERS,Alert-Info)=;volume=)") in new stack
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] pbx.c: Executing [s@macro-dial:16] Set("SIP/cbeyond-in-0000b5b5", "ITER=2") in new stack
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] pbx.c: Executing [s@macro-dial:17] GotoIf("SIP/cbeyond-in-0000b5b5", "1?ndloopbegin") in new stack
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] pbx.c: Goto (macro-dial,s,12)
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] pbx.c: Executing [s@macro-dial:12] Set("SIP/cbeyond-in-0000b5b5", "EXTTOCALL=403") in new stack
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] pbx.c: Executing [s@macro-dial:13] NoOp("SIP/cbeyond-in-0000b5b5", "Working with 403") in new stack
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] pbx.c: Executing [s@macro-dial:14] ExecIf("SIP/cbeyond-in-0000b5b5", "0?Set(HASH(__SIPHEADERS,Alert-Info)=;volume=)") in new stack
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] pbx.c: Executing [s@macro-dial:15] ExecIf("SIP/cbeyond-in-0000b5b5", "0?Set(HASH(__SIPHEADERS,Alert-Info)=;volume=)") in new stack
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] pbx.c: Executing [s@macro-dial:16] Set("SIP/cbeyond-in-0000b5b5", "ITER=3") in new stack
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] pbx.c: Executing [s@macro-dial:17] GotoIf("SIP/cbeyond-in-0000b5b5", "0?ndloopbegin") in new stack
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] pbx.c: Executing [s@macro-dial:18] Macro("SIP/cbeyond-in-0000b5b5", "dial-ringall-predial-hook,") in new stack
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] pbx.c: Executing [s@macro-dial-ringall-predial-hook:1] MacroExit("SIP/cbeyond-in-0000b5b5", "") in new stack
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] pbx.c: Executing [s@macro-dial:19] Dial("SIP/cbeyond-in-0000b5b5", "SIP/401&SIP/403,10,trM(auto-blkvm)b(func-apply-sipheaders^s^1),") in new stack
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] netsock2.c: Using SIP RTP TOS bits 184
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] netsock2.c: Using SIP RTP CoS mark 5
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] netsock2.c: Using SIP RTP TOS bits 184
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] netsock2.c: Using SIP RTP CoS mark 5
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] app_stack.c: SIP/401-0000b5b8 Internal Gosub(func-apply-sipheaders,s,1) start
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] pbx.c: Executing [s@func-apply-sipheaders:1] NoOp("SIP/401-0000b5b8", "Applying SIP Headers to channel") in new stack
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] pbx.c: Executing [s@func-apply-sipheaders:2] Set("SIP/401-0000b5b8", "SIPHEADERKEYS=") in new stack
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] pbx.c: Executing [s@func-apply-sipheaders:3] While("SIP/401-0000b5b8", "0") in new stack
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] app_while.c: Jumping to priority 7
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] pbx.c: Executing [s@func-apply-sipheaders:8] Return("SIP/401-0000b5b8", "") in new stack
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] app_stack.c: Spawn extension (from-internal, 308, 1) exited non-zero on 'SIP/401-0000b5b8'
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] app_stack.c: SIP/401-0000b5b8 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] app_stack.c: SIP/403-0000b5b9 Internal Gosub(func-apply-sipheaders,s,1) start
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] pbx.c: Executing [s@func-apply-sipheaders:1] NoOp("SIP/403-0000b5b9", "Applying SIP Headers to channel") in new stack
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] pbx.c: Executing [s@func-apply-sipheaders:2] Set("SIP/403-0000b5b9", "SIPHEADERKEYS=") in new stack
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] pbx.c: Executing [s@func-apply-sipheaders:3] While("SIP/403-0000b5b9", "0") in new stack
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] app_while.c: Jumping to priority 7
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] pbx.c: Executing [s@func-apply-sipheaders:8] Return("SIP/403-0000b5b9", "") in new stack
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] app_stack.c: Spawn extension (from-internal, 308, 1) exited non-zero on 'SIP/403-0000b5b9'
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] app_stack.c: SIP/403-0000b5b9 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2017-01-05 14:05:14] VERBOSE[16975] chan_sip.c: Extension Changed 401[ext-local] new state Ringing for Notify User 404
[2017-01-05 14:05:14] VERBOSE[16975] chan_sip.c: Extension Changed 401[ext-local] new state Ringing for Notify User 402
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] app_dial.c: Called SIP/401
[2017-01-05 14:05:14] VERBOSE[16975] chan_sip.c: Extension Changed 401[ext-local] new state Ringing for Notify User 304
[2017-01-05 14:05:14] VERBOSE[16975] chan_sip.c: Extension Changed 401[ext-local] new state Ringing for Notify User 303
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] app_dial.c: Called SIP/403
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] app_dial.c: SIP/403-0000b5b9 connected line has changed. Saving it until answer for SIP/cbeyond-in-0000b5b5
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] app_dial.c: SIP/401-0000b5b8 connected line has changed. Saving it until answer for SIP/cbeyond-in-0000b5b5
[2017-01-05 14:05:14] VERBOSE[16975] chan_sip.c: Extension Changed 403[ext-local] new state Ringing for Notify User 301
[2017-01-05 14:05:14] VERBOSE[16975] chan_sip.c: Extension Changed 403[ext-local] new state Ringing for Notify User 404
[2017-01-05 14:05:14] VERBOSE[16975] chan_sip.c: Extension Changed 403[ext-local] new state Ringing for Notify User 304
[2017-01-05 14:05:14] VERBOSE[16975] chan_sip.c: Extension Changed 403[ext-local] new state Ringing for Notify User 306
[2017-01-05 14:05:14] VERBOSE[16975] chan_sip.c: Extension Changed 403[ext-local] new state Ringing for Notify User 401
[2017-01-05 14:05:14] VERBOSE[16975] chan_sip.c: Extension Changed 403[ext-local] new state Ringing for Notify User 303
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] app_dial.c: SIP/403-0000b5b9 is ringing
[2017-01-05 14:05:14] VERBOSE[19863][C-00004813] app_dial.c: SIP/401-0000b5b8 is ringing
[2017-01-05 14:05:24] VERBOSE[19863][C-00004813] app_dial.c: Nobody picked up in 10000 ms
[2017-01-05 14:05:24] VERBOSE[16975] chan_sip.c: Extension Changed 401[ext-local] new state Idle for Notify User 404
[2017-01-05 14:05:24] VERBOSE[16975] chan_sip.c: Extension Changed 401[ext-local] new state Idle for Notify User 402
[2017-01-05 14:05:24] VERBOSE[16975] chan_sip.c: Extension Changed 401[ext-local] new state Idle for Notify User 304
[2017-01-05 14:05:24] VERBOSE[16975] chan_sip.c: Extension Changed 401[ext-local] new state Idle for Notify User 303
[2017-01-05 14:05:24] VERBOSE[16975] chan_sip.c: Extension Changed 403[ext-local] new state Idle for Notify User 301
[2017-01-05 14:05:24] VERBOSE[16975] chan_sip.c: Extension Changed 403[ext-local] new state Idle for Notify User 404
[2017-01-05 14:05:24] VERBOSE[19863][C-00004813] pbx.c: Executing [s@macro-dial:20] Set("SIP/cbeyond-in-0000b5b5", "DIALSTATUS=NOANSWER") in new stack
[2017-01-05 14:05:24] VERBOSE[19863][C-00004813] pbx.c: Executing [s@macro-dial:21] GosubIf("SIP/cbeyond-in-0000b5b5", "0?NOANSWER,1()") in new stack
[2017-01-05 14:05:24] VERBOSE[16975] chan_sip.c: Extension Changed 403[ext-local] new state Idle for Notify User 304
[2017-01-05 14:05:24] VERBOSE[16975] chan_sip.c: Extension Changed 403[ext-local] new state Idle for Notify User 306
[2017-01-05 14:05:24] VERBOSE[16975] chan_sip.c: Extension Changed 403[ext-local] new state Idle for Notify User 401
[2017-01-05 14:05:24] VERBOSE[19863][C-00004813] pbx.c: Executing [s@macro-dial:22] NoOp("SIP/cbeyond-in-0000b5b5", "Returning since nobody answered") in new stack
[2017-01-05 14:05:24] VERBOSE[16975] chan_sip.c: Extension Changed 403[ext-local] new state Idle for Notify User 303
[2017-01-05 14:05:24] VERBOSE[19863][C-00004813] pbx.c: Executing [s@macro-dial:23] MacroExit("SIP/cbeyond-in-0000b5b5", "") in new stack
[2017-01-05 14:05:24] VERBOSE[19863][C-00004813] pbx.c: Executing [308@ext-group:15] Gosub("SIP/cbeyond-in-0000b5b5", "sub-record-cancel,s,1()") in new stack
[2017-01-05 14:05:24] VERBOSE[19863][C-00004813] pbx.c: Executing [s@sub-record-cancel:1] Return("SIP/cbeyond-in-0000b5b5", "") in new stack
[2017-01-05 14:05:24] VERBOSE[19863][C-00004813] pbx.c: Executing [308@ext-group:16] Set("SIP/cbeyond-in-0000b5b5", "RingGroupMethod=") in new stack
[2017-01-05 14:05:24] VERBOSE[19863][C-00004813] pbx.c: Executing [308@ext-group:17] GotoIf("SIP/cbeyond-in-0000b5b5", "0?nodest") in new stack
[2017-01-05 14:05:24] VERBOSE[19863][C-00004813] pbx.c: Executing [308@ext-group:18] Set("SIP/cbeyond-in-0000b5b5", "__NODEST=") in new stack
[2017-01-05 14:05:24] VERBOSE[19863][C-00004813] pbx.c: Executing [308@ext-group:19] Macro("SIP/cbeyond-in-0000b5b5", "blkvm-clr,") in new stack
[2017-01-05 14:05:24] VERBOSE[19863][C-00004813] pbx.c: Executing [s@macro-blkvm-clr:1] Set("SIP/cbeyond-in-0000b5b5", "SHARED(BLKVM,SIP/cbeyond-in-0000b5b5)=") in new stack
[2017-01-05 14:05:24] VERBOSE[19863][C-00004813] pbx.c: Executing [s@macro-blkvm-clr:2] Set("SIP/cbeyond-in-0000b5b5", "GOSUB_RETVAL=") in new stack
[2017-01-05 14:05:24] VERBOSE[19863][C-00004813] pbx.c: Executing [s@macro-blkvm-clr:3] MacroExit("SIP/cbeyond-in-0000b5b5", "") in new stack
[2017-01-05 14:05:24] VERBOSE[19863][C-00004813] pbx.c: Executing [308@ext-group:20] Goto("SIP/cbeyond-in-0000b5b5", "ivr-1,s,1") in new stack
[2017-01-05 14:05:24] VERBOSE[19863][C-00004813] pbx.c: Goto (ivr-1,s,1)
[2017-01-05 14:05:24] VERBOSE[19863][C-00004813] pbx.c: Executing [s@ivr-1:1] Set("SIP/cbeyond-in-0000b5b5", "TIMEOUT_LOOPCOUNT=0") in new stack
[2017-01-05 14:05:24] VERBOSE[19863][C-00004813] pbx.c: Executing [s@ivr-1:2] Set("SIP/cbeyond-in-0000b5b5", "INVALID_LOOPCOUNT=0") in new stack
[2017-01-05 14:05:24] VERBOSE[19863][C-00004813] pbx.c: Executing [s@ivr-1:3] Set("SIP/cbeyond-in-0000b5b5", "_IVR_CONTEXT_ivr-1=") in new stack
[2017-01-05 14:05:24] VERBOSE[19863][C-00004813] pbx.c: Executing [s@ivr-1:4] Set("SIP/cbeyond-in-0000b5b5", "_IVR_CONTEXT=ivr-1") in new stack
[2017-01-05 14:05:24] VERBOSE[19863][C-00004813] pbx.c: Executing [s@ivr-1:5] Set("SIP/cbeyond-in-0000b5b5", "__IVR_RETVM=") in new stack
[2017-01-05 14:05:24] VERBOSE[19863][C-00004813] pbx.c: Executing [s@ivr-1:6] GotoIf("SIP/cbeyond-in-0000b5b5", "0?skip") in new stack
[2017-01-05 14:05:24] VERBOSE[19863][C-00004813] pbx.c: Executing [s@ivr-1:7] Answer("SIP/cbeyond-in-0000b5b5", "") in new stack
[2017-01-05 14:05:24] VERBOSE[19863][C-00004813] pbx.c: Executing [s@ivr-1:8] Wait("SIP/cbeyond-in-0000b5b5", "1") in new stack
[2017-01-05 14:05:25] VERBOSE[19863][C-00004813] pbx.c: Spawn extension (ivr-1, s, 8) exited non-zero on 'SIP/cbeyond-in-0000b5b5'
[2017-01-05 14:05:25] VERBOSE[19863][C-00004813] pbx.c: Executing [h@ivr-1:1] Hangup("SIP/cbeyond-in-0000b5b5", "") in new stack