Output of /var/log/asterisk/full around the time of the failed call. SIP and IAX2 debug turned off.
[2018-03-13 12:22:28] VERBOSE[7630] chan_iax2.c: Tx-Frame Retry[000] – OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE
[2018-03-13 12:22:28] VERBOSE[7630] chan_iax2.c: Timestamp: 00013ms SCall: 04126 DCall: 00000 10.0.1.69:4569
[2018-03-13 12:22:28] VERBOSE[7630] chan_iax2.c:
[2018-03-13 12:22:28] VERBOSE[7639] chan_iax2.c: Rx-Frame Retry[ No] – OSeqno: 000 ISeqno: 001 Type: IAX Subclass: PONG
[2018-03-13 12:22:28] VERBOSE[7639] chan_iax2.c: Timestamp: 00013ms SCall: 00001 DCall: 04126 10.0.1.69:4569
[2018-03-13 12:22:28] VERBOSE[7639] chan_iax2.c: Tx-Frame Retry[-01] – OSeqno: 001 ISeqno: 001 Type: IAX Subclass: ACK
[2018-03-13 12:22:28] VERBOSE[7639] chan_iax2.c: Timestamp: 00013ms SCall: 04126 DCall: 00001 10.0.1.69:4569
[2018-03-13 12:22:37] VERBOSE[7638] chan_iax2.c: Rx-Frame Retry[ No] – OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE
[2018-03-13 12:22:37] VERBOSE[7638] chan_iax2.c: Timestamp: 00010ms SCall: 06594 DCall: 00000 10.0.1.69:4569
[2018-03-13 12:22:37] VERBOSE[7638] chan_iax2.c:
[2018-03-13 12:22:37] VERBOSE[7638] chan_iax2.c: Tx-Frame Retry[ No] – OSeqno: 000 ISeqno: 001 Type: IAX Subclass: PONG
[2018-03-13 12:22:37] VERBOSE[7638] chan_iax2.c: Timestamp: 00010ms SCall: 00001 DCall: 06594 10.0.1.69:4569
[2018-03-13 12:22:37] VERBOSE[7641] chan_iax2.c: Rx-Frame Retry[ No] – OSeqno: 001 ISeqno: 001 Type: IAX Subclass: ACK
[2018-03-13 12:22:37] VERBOSE[7641] chan_iax2.c: Timestamp: 00010ms SCall: 06594 DCall: 00001 10.0.1.69:4569
[2018-03-13 12:23:28] VERBOSE[7630] chan_iax2.c: Tx-Frame Retry[000] – OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE
[2018-03-13 12:23:28] VERBOSE[7630] chan_iax2.c: Timestamp: 00019ms SCall: 14203 DCall: 00000 10.0.1.69:4569
[2018-03-13 12:23:28] VERBOSE[7630] chan_iax2.c:
[2018-03-13 12:23:28] VERBOSE[7642] chan_iax2.c: Rx-Frame Retry[ No] – OSeqno: 000 ISeqno: 001 Type: IAX Subclass: PONG
[2018-03-13 12:23:28] VERBOSE[7642] chan_iax2.c: Timestamp: 00019ms SCall: 00001 DCall: 14203 10.0.1.69:4569
[2018-03-13 12:23:28] VERBOSE[7642] chan_iax2.c: Tx-Frame Retry[-01] – OSeqno: 001 ISeqno: 001 Type: IAX Subclass: ACK
[2018-03-13 12:23:28] VERBOSE[7642] chan_iax2.c: Timestamp: 00019ms SCall: 14203 DCall: 00001 10.0.1.69:4569
[2018-03-13 12:23:33] VERBOSE[25429][C-000016e3] bridge_channel.c: Channel SIP/238-00006fc1 left ‘simple_bridge’ basic-bridge <43ccb0b2-6a06-4c7b-90aa-540fab52571d>
[2018-03-13 12:23:33] VERBOSE[25429][C-000016e3] app_macro.c: Spawn extension (macro-dialout-trunk, s, 31) exited non-zero on ‘SIP/238-00006fc1’ in macro ‘dialout-trunk’
[2018-03-13 12:23:33] VERBOSE[25429][C-000016e3] pbx.c: Spawn extension (from-internal, 418884237834, 7) exited non-zero on ‘SIP/238-00006fc1’
[2018-03-13 12:23:33] VERBOSE[25429][C-000016e3] pbx.c: Executing [h@from-internal:1] Macro(“SIP/238-00006fc1”, “hangupcall”) in new stack
[2018-03-13 12:23:33] VERBOSE[25490][C-000016e3] bridge_channel.c: Channel SIP/AAHTrunk-00006fc3 left ‘simple_bridge’ basic-bridge <43ccb0b2-6a06-4c7b-90aa-540fab52571d>
[2018-03-13 12:23:33] VERBOSE[25429][C-000016e3] pbx.c: Executing [s@macro-hangupcall:1] GotoIf(“SIP/238-00006fc1”, “1?theend”) in new stack
[2018-03-13 12:23:33] VERBOSE[25429][C-000016e3] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2018-03-13 12:23:33] VERBOSE[25429][C-000016e3] pbx.c: Executing [s@macro-hangupcall:3] ExecIf(“SIP/238-00006fc1”, “0?Set(CDR(recordingfile)=)”) in new stack
[2018-03-13 12:23:33] VERBOSE[25429][C-000016e3] pbx.c: Executing [s@macro-hangupcall:4] NoOp(“SIP/238-00006fc1”, "SIP/AAHTrunk-00006fc3 monior file= ") in new stack
[2018-03-13 12:23:33] VERBOSE[25429][C-000016e3] pbx.c: Executing [s@macro-hangupcall:5] AGI(“SIP/238-00006fc1”, “attendedtransfer-rec-restart.php,SIP/AAHTrunk-00006fc3,”) in new stack
[2018-03-13 12:23:34] VERBOSE[25429][C-000016e3] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/attendedtransfer-rec-restart.php
[2018-03-13 12:23:34] VERBOSE[25429][C-000016e3] res_agi.c: <SIP/238-00006fc1>AGI Script attendedtransfer-rec-restart.php completed, returning 0
[2018-03-13 12:23:34] VERBOSE[25429][C-000016e3] pbx.c: Executing [s@macro-hangupcall:6] Hangup(“SIP/238-00006fc1”, “”) in new stack
[2018-03-13 12:23:34] VERBOSE[25429][C-000016e3] app_macro.c: Spawn extension (macro-hangupcall, s, 6) exited non-zero on ‘SIP/238-00006fc1’ in macro ‘hangupcall’
[2018-03-13 12:23:34] VERBOSE[25429][C-000016e3] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on ‘SIP/238-00006fc1’
[2018-03-13 12:23:34] VERBOSE[25429][C-000016e3] app_stack.c: SIP/238-00006fc1 Internal Gosub(crm-hangup,s,1) start
[2018-03-13 12:23:34] VERBOSE[25429][C-000016e3] pbx.c: Executing [s@crm-hangup:1] NoOp(“SIP/238-00006fc1”, “Sending Hangup to CRM”) in new stack
[2018-03-13 12:23:34] VERBOSE[25429][C-000016e3] pbx.c: Executing [s@crm-hangup:2] NoOp(“SIP/238-00006fc1”, “HANGUP CAUSE: 16”) in new stack
[2018-03-13 12:23:34] VERBOSE[25429][C-000016e3] pbx.c: Executing [s@crm-hangup:3] ExecIf(“SIP/238-00006fc1”, “0?Set(__CRM_VOICEMAIL=)”) in new stack
[2018-03-13 12:23:34] VERBOSE[25429][C-000016e3] pbx.c: Executing [s@crm-hangup:4] NoOp(“SIP/238-00006fc1”, “MASTER CHANNEL: 1520965340.41398 = 1520965340.41398”) in new stack
[2018-03-13 12:23:34] VERBOSE[25429][C-000016e3] pbx.c: Executing [s@crm-hangup:5] GotoIf(“SIP/238-00006fc1”, “0?return”) in new stack
[2018-03-13 12:23:34] VERBOSE[25429][C-000016e3] pbx.c: Executing [s@crm-hangup:6] Set(“SIP/238-00006fc1”, “__CRM_HANGUP=1”) in new stack
[2018-03-13 12:23:34] VERBOSE[25429][C-000016e3] pbx.c: Executing [s@crm-hangup:7] AGI(“SIP/238-00006fc1”, “sangomacrm.agi”) in new stack
[2018-03-13 12:23:34] VERBOSE[25429][C-000016e3] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
[2018-03-13 12:23:34] VERBOSE[25429][C-000016e3] res_agi.c: <SIP/238-00006fc1>AGI Script sangomacrm.agi completed, returning 0
[2018-03-13 12:23:34] VERBOSE[25429][C-000016e3] pbx.c: Executing [s@crm-hangup:8] Return(“SIP/238-00006fc1”, “”) in new stack
[2018-03-13 12:23:34] VERBOSE[25429][C-000016e3] app_stack.c: Spawn extension (from-internal, h, 1) exited non-zero on ‘SIP/238-00006fc1’
[2018-03-13 12:23:34] VERBOSE[25429][C-000016e3] app_stack.c: SIP/238-00006fc1 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
[2018-03-13 12:23:34] VERBOSE[7580] chan_sip.c: Extension Changed 238[ext-local] new state Idle for Notify User 233
[2018-03-13 12:23:37] VERBOSE[7636] chan_iax2.c: Rx-Frame Retry[ No] – OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE
[2018-03-13 12:23:37] VERBOSE[7636] chan_iax2.c: Timestamp: 00007ms SCall: 03486 DCall: 00000 10.0.1.69:4569
[2018-03-13 12:23:37] VERBOSE[7636] chan_iax2.c:
[2018-03-13 12:23:37] VERBOSE[7636] chan_iax2.c: Tx-Frame Retry[ No] – OSeqno: 000 ISeqno: 001 Type: IAX Subclass: PONG
[2018-03-13 12:23:37] VERBOSE[7636] chan_iax2.c: Timestamp: 00007ms SCall: 00001 DCall: 03486 10.0.1.69:4569
[2018-03-13 12:23:37] VERBOSE[7634] chan_iax2.c: Rx-Frame Retry[ No] – OSeqno: 001 ISeqno: 001 Type: IAX Subclass: ACK
[2018-03-13 12:23:37] VERBOSE[7634] chan_iax2.c: Timestamp: 00007ms SCall: 03486 DCall: 00001 10.0.1.69:4569
[2018-03-13 12:23:56] VERBOSE[7551] asterisk.c: Remote UNIX connection
[2018-03-13 12:24:07] VERBOSE[7628][C-000016e5] netsock2.c: Using SIP RTP TOS bits 184
[2018-03-13 12:24:07] VERBOSE[7628][C-000016e5] netsock2.c: Using SIP RTP CoS mark 5
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [3301@from-internal:1] Macro(“SIP/201-00006fc6”, “user-callerid,LIMIT,EXTERNAL,”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-user-callerid:1] Set(“SIP/201-00006fc6”, “TOUCH_MONITOR=1520965447.41403”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-user-callerid:2] Set(“SIP/201-00006fc6”, “AMPUSER=201”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-user-callerid:3] GotoIf(“SIP/201-00006fc6”, “0?report”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-user-callerid:4] ExecIf(“SIP/201-00006fc6”, “1?Set(REALCALLERIDNUM=201)”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-user-callerid:5] Set(“SIP/201-00006fc6”, “AMPUSER=201”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-user-callerid:6] GotoIf(“SIP/201-00006fc6”, “0?limit”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-user-callerid:7] Set(“SIP/201-00006fc6”, “AMPUSERCIDNAME=Aaron”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-user-callerid:8] ExecIf(“SIP/201-00006fc6”, “0?Set(__CIDMASQUERADING=TRUE)”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-user-callerid:9] GotoIf(“SIP/201-00006fc6”, “0?report”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-user-callerid:10] Set(“SIP/201-00006fc6”, “AMPUSERCID=201”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-user-callerid:11] Set(“SIP/201-00006fc6”, “__DIAL_OPTIONS=HhTtr”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-user-callerid:12] Set(“SIP/201-00006fc6”, “CALLERID(all)=“Aaron” <201>”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-user-callerid:13] GotoIf(“SIP/201-00006fc6”, “0?limit”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-user-callerid:14] ExecIf(“SIP/201-00006fc6”, “1?Set(GROUP(concurrency_limit)=201)”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-user-callerid:15] ExecIf(“SIP/201-00006fc6”, “0?Set(CHANNEL(language)=)”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-user-callerid:16] NoOp(“SIP/201-00006fc6”, “Macro Depth is 1”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-user-callerid:17] GotoIf(“SIP/201-00006fc6”, “1?report2:macroerror”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx_builtins.c: Goto (macro-user-callerid,s,19)
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-user-callerid:19] GotoIf(“SIP/201-00006fc6”, “1?continue”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx_builtins.c: Goto (macro-user-callerid,s,37)
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-user-callerid:37] Set(“SIP/201-00006fc6”, “CALLERID(number)=201”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-user-callerid:38] Set(“SIP/201-00006fc6”, “CALLERID(name)=Aaron”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-user-callerid:39] GotoIf(“SIP/201-00006fc6”, “0?cnum”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-user-callerid:40] Set(“SIP/201-00006fc6”, “CDR(cnam)=Aaron”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-user-callerid:41] Set(“SIP/201-00006fc6”, “CDR(cnum)=201”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-user-callerid:42] Set(“SIP/201-00006fc6”, “CHANNEL(language)=en”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [3301@from-internal:2] Gosub(“SIP/201-00006fc6”, “sub-record-check,s,1(out,3301,dontcare)”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@sub-record-check:1] GotoIf(“SIP/201-00006fc6”, “0?initialized”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@sub-record-check:2] Set(“SIP/201-00006fc6”, “__REC_STATUS=INITIALIZED”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@sub-record-check:3] Set(“SIP/201-00006fc6”, “NOW=1520965447”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@sub-record-check:4] Set(“SIP/201-00006fc6”, “__DAY=13”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@sub-record-check:5] Set(“SIP/201-00006fc6”, “__MONTH=03”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@sub-record-check:6] Set(“SIP/201-00006fc6”, “__YEAR=2018”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@sub-record-check:7] Set(“SIP/201-00006fc6”, “__TIMESTR=20180313-122407”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@sub-record-check:8] Set(“SIP/201-00006fc6”, “__FROMEXTEN=201”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@sub-record-check:9] Set(“SIP/201-00006fc6”, “__MON_FMT=wav”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@sub-record-check:10] NoOp(“SIP/201-00006fc6”, “Recordings initialized”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@sub-record-check:11] ExecIf(“SIP/201-00006fc6”, “0?Set(ARG3=dontcare)”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@sub-record-check:12] Set(“SIP/201-00006fc6”, “REC_POLICY_MODE_SAVE=”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@sub-record-check:13] ExecIf(“SIP/201-00006fc6”, “0?Set(REC_STATUS=NO)”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@sub-record-check:14] GotoIf(“SIP/201-00006fc6”, “3?checkaction”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx_builtins.c: Goto (sub-record-check,s,17)
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@sub-record-check:17] GotoIf(“SIP/201-00006fc6”, “1?sub-record-check,out,1”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx_builtins.c: Goto (sub-record-check,out,1)
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [out@sub-record-check:1] NoOp(“SIP/201-00006fc6”, “Outbound Recording Check from 201 to 3301”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [out@sub-record-check:2] Set(“SIP/201-00006fc6”, “RECMODE=dontcare”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [out@sub-record-check:3] ExecIf(“SIP/201-00006fc6”, “1?Goto(routewins)”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx_builtins.c: Goto (sub-record-check,out,7)
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [out@sub-record-check:7] Gosub(“SIP/201-00006fc6”, “recordcheck,1(dontcare,out,3301)”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp(“SIP/201-00006fc6”, “Starting recording check against dontcare”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [recordcheck@sub-record-check:2] Goto(“SIP/201-00006fc6”, “dontcare”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [recordcheck@sub-record-check:3] Return(“SIP/201-00006fc6”, “”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [out@sub-record-check:8] Return(“SIP/201-00006fc6”, “”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [3301@from-internal:3] ExecIf(“SIP/201-00006fc6”, “0 ?Set(CDR(accountcode)=)”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [3301@from-internal:4] Set(“SIP/201-00006fc6”, “INTRACOMPANYROUTE=YES”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [3301@from-internal:5] Set(“SIP/201-00006fc6”, “MOHCLASS=default”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [3301@from-internal:6] ExecIf(“SIP/201-00006fc6”, “1?Set(TRUNKCIDOVERRIDE=<7196341100>)”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [3301@from-internal:7] Set(“SIP/201-00006fc6”, “_NODEST=”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [3301@from-internal:8] Macro(“SIP/201-00006fc6”, “dialout-trunk,1,301,off”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-dialout-trunk:1] Set(“SIP/201-00006fc6”, “DIAL_TRUNK=1”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-dialout-trunk:2] GosubIf(“SIP/201-00006fc6”, “0?sub-pincheck,s,1()”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-dialout-trunk:3] ExecIf(“SIP/201-00006fc6”, “0?Set(CALLERID(num)=201)”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-dialout-trunk:4] GotoIf(“SIP/201-00006fc6”, “0?disabletrunk,1”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-dialout-trunk:5] Set(“SIP/201-00006fc6”, “DIAL_NUMBER=301”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-dialout-trunk:6] Set(“SIP/201-00006fc6”, “DIAL_TRUNK_OPTIONS=HhTtr”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-dialout-trunk:7] Set(“SIP/201-00006fc6”, “OUTBOUND_GROUP=OUT_1”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-dialout-trunk:8] Set(“SIP/201-00006fc6”, “DIAL_TRUNK_OPTIONS=Ttr”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-dialout-trunk:9] GotoIf(“SIP/201-00006fc6”, “1?nomax”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx_builtins.c: Goto (macro-dialout-trunk,s,11)
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-dialout-trunk:11] GotoIf(“SIP/201-00006fc6”, “1?skipoutcid”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx_builtins.c: Goto (macro-dialout-trunk,s,13)
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-dialout-trunk:13] GosubIf(“SIP/201-00006fc6”, “0?sub-flp-1,s,1()”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-dialout-trunk:14] Set(“SIP/201-00006fc6”, “OUTNUM=301”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-dialout-trunk:15] Set(“SIP/201-00006fc6”, “custom=IAX2/InterOfficeToAB-peer”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-dialout-trunk:16] ExecIf(“SIP/201-00006fc6”, “0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)Ttr)”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-dialout-trunk:17] ExecIf(“SIP/201-00006fc6”, “0?Set(DIAL_TRUNK_OPTIONS=TtrM(confirm))”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-dialout-trunk:18] Macro(“SIP/201-00006fc6”, “dialout-trunk-predial-hook,”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit(“SIP/201-00006fc6”, “”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-dialout-trunk:19] GotoIf(“SIP/201-00006fc6”, “0?skipcrm”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-dialout-trunk:20] Set(“SIP/201-00006fc6”, “__CRM_DIRECTION=OUTBOUND”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-dialout-trunk:21] Set(“SIP/201-00006fc6”, “__CRM_DESTINATION=301”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-dialout-trunk:22] Set(“SIP/201-00006fc6”, “__CRM_SOURCE=201”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-dialout-trunk:23] AGI(“SIP/201-00006fc6”, “sangomacrm.agi”) in new stack
[2018-03-13 12:24:07] VERBOSE[26489][C-000016e5] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
[2018-03-13 12:24:08] VERBOSE[26489][C-000016e5] res_agi.c: <SIP/201-00006fc6>AGI Script sangomacrm.agi completed, returning 0
[2018-03-13 12:24:08] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-dialout-trunk:24] Set(“SIP/201-00006fc6”, “CHANNEL(hangup_handler_push)=crm-hangup,s,1”) in new stack
[2018-03-13 12:24:08] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-dialout-trunk:25] NoOp(“SIP/201-00006fc6”, “CRM Finished”) in new stack
[2018-03-13 12:24:08] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-dialout-trunk:26] GotoIf(“SIP/201-00006fc6”, “0?bypass,1”) in new stack
[2018-03-13 12:24:08] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-dialout-trunk:27] ExecIf(“SIP/201-00006fc6”, “1?Set(CONNECTEDLINE(num,i)=301)”) in new stack
[2018-03-13 12:24:08] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-dialout-trunk:28] ExecIf(“SIP/201-00006fc6”, “1?Set(CONNECTEDLINE(name,i)=CID:201)”) in new stack
[2018-03-13 12:24:08] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-dialout-trunk:29] ExecIf(“SIP/201-00006fc6”, “0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)201)”) in new stack
[2018-03-13 12:24:08] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-dialout-trunk:30] GotoIf(“SIP/201-00006fc6”, “0?customtrunk”) in new stack
[2018-03-13 12:24:08] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-dialout-trunk:31] Dial(“SIP/201-00006fc6”, “IAX2/InterOfficeToAB-peer/301,300,Ttrb(func-apply-sipheaders^s^1)”) in new stack
[2018-03-13 12:24:08] VERBOSE[26489][C-000016e5] app_stack.c: IAX2/InterOfficeToAB-peer-30228 Internal Gosub(func-apply-sipheaders,s,1) start
[2018-03-13 12:24:08] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf(“IAX2/InterOfficeToAB-peer-30228”, “0?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)”) in new stack
[2018-03-13 12:24:08] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp(“IAX2/InterOfficeToAB-peer-30228”, “Applying SIP Headers to channel”) in new stack
[2018-03-13 12:24:08] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@func-apply-sipheaders:3] Set(“IAX2/InterOfficeToAB-peer-30228”, “SIPHEADERKEYS=”) in new stack
[2018-03-13 12:24:08] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@func-apply-sipheaders:4] While(“IAX2/InterOfficeToAB-peer-30228”, “0”) in new stack
[2018-03-13 12:24:08] VERBOSE[26489][C-000016e5] app_while.c: Jumping to priority 8
[2018-03-13 12:24:08] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@func-apply-sipheaders:9] Return(“IAX2/InterOfficeToAB-peer-30228”, “”) in new stack
[2018-03-13 12:24:08] VERBOSE[26489][C-000016e5] app_stack.c: Spawn extension (, 3301, 1) exited non-zero on ‘IAX2/InterOfficeToAB-peer-30228’
[2018-03-13 12:24:08] VERBOSE[26489][C-000016e5] app_stack.c: IAX2/InterOfficeToAB-peer-30228 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2018-03-13 12:24:08] VERBOSE[26489][C-000016e5] app_dial.c: Called IAX2/InterOfficeToAB-peer/301
[2018-03-13 12:24:08] VERBOSE[26489][C-000016e5] chan_iax2.c: Hungup ‘IAX2/InterOfficeToAB-peer-30228’
[2018-03-13 12:24:08] VERBOSE[26489][C-000016e5] app_dial.c: Everyone is busy/congested at this time (1:0/0/1)
[2018-03-13 12:24:08] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-dialout-trunk:32] NoOp(“SIP/201-00006fc6”, “Dial failed for some reason with DIALSTATUS = CHANUNAVAIL and HANGUPCAUSE = 50”) in new stack
[2018-03-13 12:24:08] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-dialout-trunk:33] GotoIf(“SIP/201-00006fc6”, “0?continue,1:s-CHANUNAVAIL,1”) in new stack
[2018-03-13 12:24:08] VERBOSE[26489][C-000016e5] pbx_builtins.c: Goto (macro-dialout-trunk,s-CHANUNAVAIL,1)
[2018-03-13 12:24:08] VERBOSE[26489][C-000016e5] pbx.c: Executing [s-CHANUNAVAIL@macro-dialout-trunk:1] Set(“SIP/201-00006fc6”, “RC=50”) in new stack
[2018-03-13 12:24:08] VERBOSE[26489][C-000016e5] pbx.c: Executing [s-CHANUNAVAIL@macro-dialout-trunk:2] Goto(“SIP/201-00006fc6”, “50,1”) in new stack
[2018-03-13 12:24:08] VERBOSE[26489][C-000016e5] pbx_builtins.c: Goto (macro-dialout-trunk,50,1)
[2018-03-13 12:24:08] VERBOSE[26489][C-000016e5] pbx.c: Executing [50@macro-dialout-trunk:1] Goto(“SIP/201-00006fc6”, “continue,1”) in new stack
[2018-03-13 12:24:08] VERBOSE[26489][C-000016e5] pbx_builtins.c: Goto (macro-dialout-trunk,continue,1)
[2018-03-13 12:24:08] VERBOSE[26489][C-000016e5] pbx.c: Executing [continue@macro-dialout-trunk:1] NoOp(“SIP/201-00006fc6”, “TRUNK Dial failed due to CHANUNAVAIL HANGUPCAUSE: 50 - failing through to other trunks”) in new stack
[2018-03-13 12:24:08] VERBOSE[26489][C-000016e5] pbx.c: Executing [continue@macro-dialout-trunk:2] ExecIf(“SIP/201-00006fc6”, “1?Set(CALLERID(number)=201)”) in new stack
[2018-03-13 12:24:08] VERBOSE[26489][C-000016e5] pbx.c: Executing [3301@from-internal:9] Macro(“SIP/201-00006fc6”, “outisbusy,”) in new stack
[2018-03-13 12:24:08] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-outisbusy:1] Progress(“SIP/201-00006fc6”, “”) in new stack
[2018-03-13 12:24:08] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-outisbusy:2] GotoIf(“SIP/201-00006fc6”, “0?emergency,1”) in new stack
[2018-03-13 12:24:08] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-outisbusy:3] GotoIf(“SIP/201-00006fc6”, “1?intracompany,1”) in new stack
[2018-03-13 12:24:08] VERBOSE[26489][C-000016e5] pbx_builtins.c: Goto (macro-outisbusy,intracompany,1)
[2018-03-13 12:24:08] VERBOSE[26489][C-000016e5] pbx.c: Executing [intracompany@macro-outisbusy:1] Playback(“SIP/201-00006fc6”, “all-circuits-busy-now&please-try-call-later, noanswer”) in new stack
[2018-03-13 12:24:08] VERBOSE[26489][C-000016e5] file.c: <SIP/201-00006fc6> Playing ‘all-circuits-busy-now.ulaw’ (language ‘en’)
[2018-03-13 12:24:10] VERBOSE[26489][C-000016e5] file.c: <SIP/201-00006fc6> Playing ‘please-try-call-later.ulaw’ (language ‘en’)
[2018-03-13 12:24:10] VERBOSE[26489][C-000016e5] pbx.c: Executing [h@from-internal:1] Macro(“SIP/201-00006fc6”, “hangupcall”) in new stack
[2018-03-13 12:24:10] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-hangupcall:1] GotoIf(“SIP/201-00006fc6”, “1?theend”) in new stack
[2018-03-13 12:24:10] VERBOSE[26489][C-000016e5] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2018-03-13 12:24:10] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-hangupcall:3] ExecIf(“SIP/201-00006fc6”, “0?Set(CDR(recordingfile)=)”) in new stack
[2018-03-13 12:24:10] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-hangupcall:4] NoOp(“SIP/201-00006fc6”, " monior file= ") in new stack
[2018-03-13 12:24:10] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-hangupcall:5] AGI(“SIP/201-00006fc6”, “attendedtransfer-rec-restart.php,”) in new stack
[2018-03-13 12:24:10] VERBOSE[26489][C-000016e5] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/attendedtransfer-rec-restart.php
[2018-03-13 12:24:10] VERBOSE[26489][C-000016e5] res_agi.c: <SIP/201-00006fc6>AGI Script attendedtransfer-rec-restart.php completed, returning 0
[2018-03-13 12:24:10] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@macro-hangupcall:6] Hangup(“SIP/201-00006fc6”, “”) in new stack
[2018-03-13 12:24:10] VERBOSE[26489][C-000016e5] app_macro.c: Spawn extension (macro-hangupcall, s, 6) exited non-zero on ‘SIP/201-00006fc6’ in macro ‘hangupcall’
[2018-03-13 12:24:10] VERBOSE[26489][C-000016e5] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on ‘SIP/201-00006fc6’
[2018-03-13 12:24:10] VERBOSE[26489][C-000016e5] app_stack.c: SIP/201-00006fc6 Internal Gosub(crm-hangup,s,1) start
[2018-03-13 12:24:10] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@crm-hangup:1] NoOp(“SIP/201-00006fc6”, “Sending Hangup to CRM”) in new stack
[2018-03-13 12:24:10] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@crm-hangup:2] NoOp(“SIP/201-00006fc6”, “HANGUP CAUSE: 50”) in new stack
[2018-03-13 12:24:10] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@crm-hangup:3] ExecIf(“SIP/201-00006fc6”, “0?Set(__CRM_VOICEMAIL=)”) in new stack
[2018-03-13 12:24:10] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@crm-hangup:4] NoOp(“SIP/201-00006fc6”, “MASTER CHANNEL: 1520965447.41403 = 1520965447.41403”) in new stack
[2018-03-13 12:24:10] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@crm-hangup:5] GotoIf(“SIP/201-00006fc6”, “0?return”) in new stack
[2018-03-13 12:24:10] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@crm-hangup:6] Set(“SIP/201-00006fc6”, “__CRM_HANGUP=1”) in new stack
[2018-03-13 12:24:10] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@crm-hangup:7] AGI(“SIP/201-00006fc6”, “sangomacrm.agi”) in new stack
[2018-03-13 12:24:10] VERBOSE[26489][C-000016e5] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
[2018-03-13 12:24:10] VERBOSE[26489][C-000016e5] res_agi.c: <SIP/201-00006fc6>AGI Script sangomacrm.agi completed, returning 0
[2018-03-13 12:24:10] VERBOSE[26489][C-000016e5] pbx.c: Executing [s@crm-hangup:8] Return(“SIP/201-00006fc6”, “”) in new stack
[2018-03-13 12:24:10] VERBOSE[26489][C-000016e5] app_stack.c: Spawn extension (from-internal, h, 1) exited non-zero on ‘SIP/201-00006fc6’
[2018-03-13 12:24:10] VERBOSE[26489][C-000016e5] app_stack.c: SIP/201-00006fc6 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
[2018-03-13 12:24:12] VERBOSE[26331] asterisk.c: Remote UNIX connection disconnected