Call Timeout over network

Hi,

We have 1 FreePBX server in 1 office and we have 3 offices in total.

We have recently been getting call timeouts or one way silence on calls and I have no idea where to look:

This is a call log from when it timed out and hung up:

Please let me know if I need to provide any information

[2015-08-27 09:42:02] VERBOSE[26102][C-0000001b] bridge_channel.c: Channel PJSIP/1701-00000024 left ‘simple_bridge’ basic-bridge <1e4b2948-0ed7-49f2-a168-daacf2012aa5>
[2015-08-27 09:42:02] VERBOSE[26129][C-0000001b] bridge_channel.c: Channel PJSIP/202-00000025 left ‘simple_bridge’ basic-bridge <1e4b2948-0ed7-49f2-a168-daacf2012aa5>
[2015-08-27 09:42:02] VERBOSE[26102][C-0000001b] app_macro.c: Spawn extension (macro-dial-one, s, 43) exited non-zero on ‘PJSIP/1701-00000024’ in macro ‘dial-one’
[2015-08-27 09:42:02] VERBOSE[26102][C-0000001b] app_macro.c: Spawn extension (macro-exten-vm, s, 16) exited non-zero on ‘PJSIP/1701-00000024’ in macro ‘exten-vm’
[2015-08-27 09:42:02] VERBOSE[26102][C-0000001b] pbx.c: Spawn extension (from-internal, 202, 2) exited non-zero on ‘PJSIP/1701-00000024’
[2015-08-27 09:42:02] VERBOSE[26102][C-0000001b] pbx.c: Executing [h@from-internal:1] Hangup(“PJSIP/1701-00000024”, “”) in new stack
[2015-08-27 09:42:02] VERBOSE[26102][C-0000001b] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on ‘PJSIP/1701-00000024’
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [202@from-internal:1] Set(“PJSIP/1701-00000026”, “__RINGTIMER=15”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [202@from-internal:2] Macro(“PJSIP/1701-00000026”, “exten-vm,novm,202,0,0,0”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-exten-vm:1] Macro(“PJSIP/1701-00000026”, “user-callerid,”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-user-callerid:1] Set(“PJSIP/1701-00000026”, “TOUCH_MONITOR=1440664927.284”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-user-callerid:2] Set(“PJSIP/1701-00000026”, “AMPUSER=1701”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-user-callerid:3] GotoIf(“PJSIP/1701-00000026”, “0?report”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-user-callerid:4] ExecIf(“PJSIP/1701-00000026”, “1?Set(REALCALLERIDNUM=1701)”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-user-callerid:5] Set(“PJSIP/1701-00000026”, “AMPUSER=1701”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-user-callerid:6] GotoIf(“PJSIP/1701-00000026”, “0?limit”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-user-callerid:7] Set(“PJSIP/1701-00000026”, “AMPUSERCIDNAME=”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-user-callerid:8] GotoIf(“PJSIP/1701-00000026”, “0?report”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-user-callerid:9] Set(“PJSIP/1701-00000026”, “AMPUSERCID=1701”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-user-callerid:10] Set(“PJSIP/1701-00000026”, “__DIAL_OPTIONS=Ttr”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-user-callerid:11] Set(“PJSIP/1701-00000026”, “CALLERID(all)=”" <1701>") in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-user-callerid:12] GotoIf(“PJSIP/1701-00000026”, “0?limit”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-user-callerid:13] ExecIf(“PJSIP/1701-00000026”, “0?Set(GROUP(concurrency_limit)=1701)”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-user-callerid:14] GosubIf(“PJSIP/1701-00000026”, “7?sub-ccss,s,1(macro-exten-vm,)”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@sub-ccss:1] ExecIf(“PJSIP/1701-00000026”, “0?Return()”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@sub-ccss:2] Set(“PJSIP/1701-00000026”, “CCSS_SETUP=TRUE”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@sub-ccss:3] GosubIf(“PJSIP/1701-00000026”, “0?monitor_config,1(macro-exten-vm,):monitor_default,1(macro-exten-vm,)”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [monitor_default@sub-ccss:1] GotoIf(“PJSIP/1701-00000026”, “0?is_exten”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [monitor_default@sub-ccss:2] StackPop(“PJSIP/1701-00000026”, “”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [monitor_default@sub-ccss:3] Return(“PJSIP/1701-00000026”, “FALSE”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-user-callerid:15] ExecIf(“PJSIP/1701-00000026”, “0?Set(CHANNEL(language)=)”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-user-callerid:16] GotoIf(“PJSIP/1701-00000026”, “0?continue”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-user-callerid:17] ExecIf(“PJSIP/1701-00000026”, “1?Set(__CALLEE_ACCOUNCODE=)”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-user-callerid:18] Set(“PJSIP/1701-00000026”, “__TTL=64”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-user-callerid:19] GotoIf(“PJSIP/1701-00000026”, “1?continue”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Goto (macro-user-callerid,s,30)
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-user-callerid:30] Set(“PJSIP/1701-00000026”, “CALLERID(number)=1701”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-user-callerid:31] Set(“PJSIP/1701-00000026”, “CALLERID(name)”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-user-callerid:32] Set(“PJSIP/1701-00000026”, “CDR(cnum)=1701”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-user-callerid:33] Set(“PJSIP/1701-00000026”, “CDR(cnam)=”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-user-callerid:34] Set(“PJSIP/1701-00000026”, “CHANNEL(language)=en”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-exten-vm:2] Set(“PJSIP/1701-00000026”, “RingGroupMethod=none”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-exten-vm:3] Set(“PJSIP/1701-00000026”, “__EXTTOCALL=202”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-exten-vm:4] Set(“PJSIP/1701-00000026”, “__PICKUPMARK=202”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-exten-vm:5] Set(“PJSIP/1701-00000026”, “RT=”) in new stack
[2015-08-27 09:42:07] WARNING[26168][C-0000001c] chan_sip.c: This function can only be used on SIP channels.
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-exten-vm:6] ExecIf(“PJSIP/1701-00000026”, “0?Macro(vm,novm,DIRECTDIAL,)”) in new stack
[2015-08-27 09:42:07] WARNING[26168][C-0000001c] chan_sip.c: This function can only be used on SIP channels.
[2015-08-27 09:42:07] WARNING[26168][C-0000001c] chan_sip.c: This function can only be used on SIP channels.
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-exten-vm:7] ExecIf(“PJSIP/1701-00000026”, “0?MacroExit()”) in new stack
[2015-08-27 09:42:07] WARNING[26168][C-0000001c] chan_sip.c: This function can only be used on SIP channels.
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-exten-vm:8] Gosub(“PJSIP/1701-00000026”, “sub-record-check,s,1(exten,202,dontcare)”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@sub-record-check:1] GotoIf(“PJSIP/1701-00000026”, “0?initialized”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@sub-record-check:2] Set(“PJSIP/1701-00000026”, “__REC_STATUS=INITIALIZED”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@sub-record-check:3] Set(“PJSIP/1701-00000026”, “NOW=1440664927”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@sub-record-check:4] Set(“PJSIP/1701-00000026”, “__DAY=27”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@sub-record-check:5] Set(“PJSIP/1701-00000026”, “__MONTH=08”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@sub-record-check:6] Set(“PJSIP/1701-00000026”, “__YEAR=2015”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@sub-record-check:7] Set(“PJSIP/1701-00000026”, “__TIMESTR=20150827-094207”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@sub-record-check:8] Set(“PJSIP/1701-00000026”, “__FROMEXTEN=1701”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@sub-record-check:9] Set(“PJSIP/1701-00000026”, “__MON_FMT=wav”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@sub-record-check:10] NoOp(“PJSIP/1701-00000026”, “Recordings initialized”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@sub-record-check:11] ExecIf(“PJSIP/1701-00000026”, “0?Set(ARG3=dontcare)”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@sub-record-check:12] Set(“PJSIP/1701-00000026”, “REC_POLICY_MODE_SAVE=”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@sub-record-check:13] ExecIf(“PJSIP/1701-00000026”, “0?Set(REC_STATUS=NO)”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@sub-record-check:14] GotoIf(“PJSIP/1701-00000026”, “5?checkaction”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Goto (sub-record-check,s,17)
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@sub-record-check:17] GotoIf(“PJSIP/1701-00000026”, “1?sub-record-check,exten,1”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Goto (sub-record-check,exten,1)
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [exten@sub-record-check:1] NoOp(“PJSIP/1701-00000026”, “Exten Recording Check between 1701 and 202”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [exten@sub-record-check:2] Set(“PJSIP/1701-00000026”, “CALLTYPE=internal”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [exten@sub-record-check:3] ExecIf(“PJSIP/1701-00000026”, “0?Set(CALLTYPE=)”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [exten@sub-record-check:4] Set(“PJSIP/1701-00000026”, “CALLEE=dontcare”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [exten@sub-record-check:5] ExecIf(“PJSIP/1701-00000026”, “0?Set(CALLEE=dontcare)”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [exten@sub-record-check:6] GotoIf(“PJSIP/1701-00000026”, “0?callee”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [exten@sub-record-check:7] GotoIf(“PJSIP/1701-00000026”, “1?caller”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Goto (sub-record-check,exten,13)
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [exten@sub-record-check:13] Set(“PJSIP/1701-00000026”, “RECMODE=dontcare”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [exten@sub-record-check:14] ExecIf(“PJSIP/1701-00000026”, “0?Set(RECMODE=dontcare)”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [exten@sub-record-check:15] ExecIf(“PJSIP/1701-00000026”, “1?Set(RECMODE=dontcare)”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [exten@sub-record-check:16] Gosub(“PJSIP/1701-00000026”, “recordcheck,1(dontcare,internal,202)”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp(“PJSIP/1701-00000026”, “Starting recording check against dontcare”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [recordcheck@sub-record-check:2] Goto(“PJSIP/1701-00000026”, “dontcare”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Goto (sub-record-check,recordcheck,3)
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [recordcheck@sub-record-check:3] Return(“PJSIP/1701-00000026”, “”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [exten@sub-record-check:17] Return(“PJSIP/1701-00000026”, “”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-exten-vm:9] GotoIf(“PJSIP/1701-00000026”, “1?macrodial”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Goto (macro-exten-vm,s,15)
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-exten-vm:15] GosubIf(“PJSIP/1701-00000026”, “0?clrheader,1()”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-exten-vm:16] Macro(“PJSIP/1701-00000026”, “dial-one,Ttr,202”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-dial-one:1] Set(“PJSIP/1701-00000026”, “DEXTEN=202”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-dial-one:2] Set(“PJSIP/1701-00000026”, “DIALSTATUS_CW=”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-dial-one:3] GosubIf(“PJSIP/1701-00000026”, “0?screen,1()”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-dial-one:4] GosubIf(“PJSIP/1701-00000026”, “0?cf,1()”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-dial-one:5] GotoIf(“PJSIP/1701-00000026”, “1?skip1”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Goto (macro-dial-one,s,8)
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-dial-one:8] GotoIf(“PJSIP/1701-00000026”, “0?nodial”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-dial-one:9] GotoIf(“PJSIP/1701-00000026”, “0?continue”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-dial-one:10] Set(“PJSIP/1701-00000026”, “EXTHASCW=ENABLED”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-dial-one:11] GotoIf(“PJSIP/1701-00000026”, “0?next1:cwinusebusy”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Goto (macro-dial-one,s,23)
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-dial-one:23] GotoIf(“PJSIP/1701-00000026”, “0?next3:continue”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Goto (macro-dial-one,s,25)
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-dial-one:25] GotoIf(“PJSIP/1701-00000026”, “0?nodial”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-dial-one:26] GosubIf(“PJSIP/1701-00000026”, “1?dstring,1():dlocal,1()”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [dstring@macro-dial-one:1] Set(“PJSIP/1701-00000026”, “DSTRING=”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [dstring@macro-dial-one:2] Set(“PJSIP/1701-00000026”, “DEVICES=202”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [dstring@macro-dial-one:3] ExecIf(“PJSIP/1701-00000026”, “0?Return()”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [dstring@macro-dial-one:4] ExecIf(“PJSIP/1701-00000026”, “0?Set(DEVICES=02)”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [dstring@macro-dial-one:5] Set(“PJSIP/1701-00000026”, “LOOPCNT=1”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [dstring@macro-dial-one:6] Set(“PJSIP/1701-00000026”, “ITER=1”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [dstring@macro-dial-one:7] Set(“PJSIP/1701-00000026”, “THISDIAL=PJSIP/202”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [dstring@macro-dial-one:8] GosubIf(“PJSIP/1701-00000026”, “1?zap2dahdi,1()”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [zap2dahdi@macro-dial-one:1] ExecIf(“PJSIP/1701-00000026”, “0?Return()”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [zap2dahdi@macro-dial-one:2] Set(“PJSIP/1701-00000026”, “NEWDIAL=”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [zap2dahdi@macro-dial-one:3] Set(“PJSIP/1701-00000026”, “LOOPCNT2=1”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [zap2dahdi@macro-dial-one:4] Set(“PJSIP/1701-00000026”, “ITER2=1”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [zap2dahdi@macro-dial-one:5] Set(“PJSIP/1701-00000026”, “THISPART2=PJSIP/202”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [zap2dahdi@macro-dial-one:6] ExecIf(“PJSIP/1701-00000026”, “0?Set(THISPART2=DAHDIIP/202)”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [zap2dahdi@macro-dial-one:7] Set(“PJSIP/1701-00000026”, “NEWDIAL=PJSIP/202&”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [zap2dahdi@macro-dial-one:8] Set(“PJSIP/1701-00000026”, “ITER2=2”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [zap2dahdi@macro-dial-one:9] GotoIf(“PJSIP/1701-00000026”, “0?begin2”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [zap2dahdi@macro-dial-one:10] Set(“PJSIP/1701-00000026”, “THISDIAL=PJSIP/202”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [zap2dahdi@macro-dial-one:11] Return(“PJSIP/1701-00000026”, “”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [dstring@macro-dial-one:9] GotoIf(“PJSIP/1701-00000026”, “0?doset”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [dstring@macro-dial-one:10] NoOp(“PJSIP/1701-00000026”, “Debug: Found PJSIP Destination PJSIP/202, updating with PJSIP_DIAL_CONTACTS”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [dstring@macro-dial-one:11] Set(“PJSIP/1701-00000026”, “THISDIAL=PJSIP/202/sip:[email protected]:5064”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [dstring@macro-dial-one:12] GotoIf(“PJSIP/1701-00000026”, “0?skipset”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [dstring@macro-dial-one:13] Set(“PJSIP/1701-00000026”, “DSTRING=PJSIP/202/sip:[email protected]:5064&”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [dstring@macro-dial-one:14] Set(“PJSIP/1701-00000026”, “ITER=2”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [dstring@macro-dial-one:15] GotoIf(“PJSIP/1701-00000026”, “0?begin”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [dstring@macro-dial-one:16] ExecIf(“PJSIP/1701-00000026”, “0?Return()”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [dstring@macro-dial-one:17] Set(“PJSIP/1701-00000026”, “DSTRING=PJSIP/202/sip:[email protected]:5064”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [dstring@macro-dial-one:18] Return(“PJSIP/1701-00000026”, “”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-dial-one:27] GotoIf(“PJSIP/1701-00000026”, “0?nodial”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-dial-one:28] GotoIf(“PJSIP/1701-00000026”, “0?skiptrace”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-dial-one:29] GosubIf(“PJSIP/1701-00000026”, “1?ctset,1():ctclear,1()”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [ctset@macro-dial-one:1] Set(“PJSIP/1701-00000026”, “DB(CALLTRACE/202)=1701”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [ctset@macro-dial-one:2] Return(“PJSIP/1701-00000026”, “”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-dial-one:30] Set(“PJSIP/1701-00000026”, “D_OPTIONS=Ttr”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-dial-one:31] GosubIf(“PJSIP/1701-00000026”, “0?func-set-sipheader,s,1(Alert-Info,)()”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-dial-one:32] ExecIf(“PJSIP/1701-00000026”, “0?Set(CHANNEL(musicclass)=)”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-dial-one:33] GosubIf(“PJSIP/1701-00000026”, “0?qwait,1()”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-dial-one:34] Set(“PJSIP/1701-00000026”, “__CWIGNORE=”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-dial-one:35] Set(“PJSIP/1701-00000026”, “__KEEPCID=TRUE”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-dial-one:36] GotoIf(“PJSIP/1701-00000026”, “0?usegoto,1”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-dial-one:37] GotoIf(“PJSIP/1701-00000026”, “0?godial”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-dial-one:38] Gosub(“PJSIP/1701-00000026”, “sub-presencestate-display,s,1(202)”) in new stack
[2015-08-27 09:42:07] WARNING[26168][C-0000001c] func_presencestate.c: PRESENCE_STATE unknown
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@sub-presencestate-display:1] Goto(“PJSIP/1701-00000026”, “state-,1”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Goto (sub-presencestate-display,state-,1)
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [state-@sub-presencestate-display:1] Set(“PJSIP/1701-00000026”, “PRESENCESTATE_DISPLAY=”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [state-@sub-presencestate-display:2] Return(“PJSIP/1701-00000026”, “”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-dial-one:39] Set(“PJSIP/1701-00000026”, “CONNECTEDLINE(name,i)=”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-dial-one:40] Set(“PJSIP/1701-00000026”, “CONNECTEDLINE(num)=202”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-dial-one:41] Set(“PJSIP/1701-00000026”, “D_OPTIONS=TtrI”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-dial-one:42] Macro(“PJSIP/1701-00000026”, “dialout-one-predial-hook,”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-dialout-one-predial-hook:1] MacroExit(“PJSIP/1701-00000026”, “”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@macro-dial-one:43] Dial(“PJSIP/1701-00000026”, “PJSIP/202/sip:[email protected]:5064,TtrIb(func-apply-sipheaders^s^1)”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] app_stack.c: PJSIP/202-00000027 Internal Gosub(func-apply-sipheaders,s,1) start
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@func-apply-sipheaders:1] NoOp(“PJSIP/202-00000027”, “Applying SIP Headers to channel”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@func-apply-sipheaders:2] Set(“PJSIP/202-00000027”, “SIPHEADERKEYS=”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@func-apply-sipheaders:3] While(“PJSIP/202-00000027”, “0”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] app_while.c: Jumping to priority 7
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] pbx.c: Executing [s@func-apply-sipheaders:8] Return(“PJSIP/202-00000027”, “”) in new stack
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] app_stack.c: Spawn extension (from-internal, 202, 1) exited non-zero on ‘PJSIP/202-00000027’
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] app_stack.c: PJSIP/202-00000027 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] app_dial.c: Called PJSIP/202/sip:[email protected]:5064
[2015-08-27 09:42:07] VERBOSE[26168][C-0000001c] app_dial.c: Connected line update to PJSIP/1701-00000026 prevented.
[2015-08-27 09:42:08] VERBOSE[26168][C-0000001c] app_dial.c: PJSIP/202-00000027 is ringing
[2015-08-27 09:42:10] VERBOSE[26168][C-0000001c] app_dial.c: PJSIP/202-00000027 answered PJSIP/1701-00000026
[2015-08-27 09:42:10] VERBOSE[26173][C-0000001c] bridge_channel.c: Channel PJSIP/202-00000027 joined ‘simple_bridge’ basic-bridge
[2015-08-27 09:42:10] VERBOSE[26168][C-0000001c] bridge_channel.c: Channel PJSIP/1701-00000026 joined ‘simple_bridge’ basic-bridge
[2015-08-27 09:42:11] WARNING[14670] res_pjsip_mwi.c: Unable to locate aor 1. MWI subscription failed.
[2015-08-27 09:42:18] WARNING[14670] res_pjsip_mwi.c: Unable to locate aor 1. MWI subscription failed.
[2015-08-27 09:42:32] WARNING[10879] res_pjsip_mwi.c: Unable to locate aor 1. MWI subscription failed.
[2015-08-27 09:42:39] WARNING[10879] res_pjsip_mwi.c: Unable to locate aor 1. MWI subscription failed.
[2015-08-27 09:42:42] VERBOSE[26168][C-0000001c] bridge_channel.c: Channel PJSIP/1701-00000026 left ‘simple_bridge’ basic-bridge
[2015-08-27 09:42:42] VERBOSE[26173][C-0000001c] bridge_channel.c: Channel PJSIP/202-00000027 left ‘simple_bridge’ basic-bridge
[2015-08-27 09:42:42] VERBOSE[26168][C-0000001c] app_macro.c: Spawn extension (macro-dial-one, s, 43) exited non-zero on ‘PJSIP/1701-00000026’ in macro ‘dial-one’
[2015-08-27 09:42:42] VERBOSE[26168][C-0000001c] app_macro.c: Spawn extension (macro-exten-vm, s, 16) exited non-zero on ‘PJSIP/1701-00000026’ in macro ‘exten-vm’
[2015-08-27 09:42:42] VERBOSE[26168][C-0000001c] pbx.c: Spawn extension (from-internal, 202, 2) exited non-zero on ‘PJSIP/1701-00000026’
[2015-08-27 09:42:42] VERBOSE[26168][C-0000001c] pbx.c: Executing [h@from-internal:1] Hangup(“PJSIP/1701-00000026”, “”) in new stack
[2015-08-27 09:42:42] VERBOSE[26168][C-0000001c] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on ‘PJSIP/1701-00000026’

These are my sip settings:

Global Settings:

UDP Bindaddress: 0.0.0.0:5061
TCP SIP Bindaddress: Disabled
TLS SIP Bindaddress: Disabled
Videosupport: No
Textsupport: No
Ignore SDP sess. ver.: No
AutoCreate Peer: Off
Match Auth Username: No
Allow unknown access: Yes
Allow subscriptions: Yes
Allow overlap dialing: Yes
Allow promisc. redir: No
Enable call counters: No
SIP domain support: No
Path support : No
Realm. auth: No
Our auth realm asterisk
Use domains as realms: No
Call to non-local dom.: Yes
URI user is phone no: No
Always auth rejects: Yes
Direct RTP setup: No
User Agent: FPBX-13.0.1beta3.54(13.5.0)
SDP Session Name: Asterisk PBX 13.5.0
SDP Owner Name: root
Reg. context: (not set)
Regexten on Qualify: No
Trust RPID: No
Send RPID: No
Legacy userfield parse: No
Send Diversion: Yes
Caller ID: Unknown
From: Domain:
Record SIP history: Off
Auth. Failure Events: Off
T.38 support: Yes
T.38 EC mode: Redundancy
T.38 MaxDtgrm: 400
SIP realtime: Disabled
Qualify Freq : 60000 ms
Q.850 Reason header: No
Store SIP_CAUSE: No

Network QoS Settings:

IP ToS SIP: CS3
IP ToS RTP audio: EF
IP ToS RTP video: AF41
IP ToS RTP text: CS0
802.1p CoS SIP: 4
802.1p CoS RTP audio: 5
802.1p CoS RTP video: 6
802.1p CoS RTP text: 5
Jitterbuffer enabled: No

Network Settings:

SIP address remapping: Disabled, no localnet list
Externhost:
Externaddr: 212.67.124.195:0
Externrefresh: 10

Global Signalling Settings:

Codecs: (ulaw|alaw|gsm|g726)
Relax DTMF: No
RFC2833 Compensation: No
Symmetric RTP: No
Compact SIP headers: No
RTP Keepalive: 0 (Disabled)
RTP Timeout: 30
RTP Hold Timeout: 300
MWI NOTIFY mime type: application/simple-message-summary
DNS SRV lookup: No
Pedantic SIP support: Yes
Reg. min duration 60 secs
Reg. max duration: 3600 secs
Reg. default duration: 120 secs
Sub. min duration 60 secs
Sub. max duration: 3600 secs
Outbound reg. timeout: 20 secs
Outbound reg. attempts: 0
Outbound reg. retry 403:0
Notify ringing state: Yes
Include CID: No
Notify hold state: Yes
SIP Transfer mode: open
Max Call Bitrate: 384 kbps
Auto-Framing: No
Outb. proxy:
Session Timers: Accept
Session Refresher: uas
Session Expires: 1800 secs
Session Min-SE: 90 secs
Timer T1: 500
Timer T1 minimum: 100
Timer B: 32000
No premature media: Yes
Max forwards: 70

Default Settings:

Allowed transports: UDP
Outbound transport: UDP
Context: from-sip-external
Record on feature: automon
Record off feature: automon
Force rport: No
DTMF: rfc2833
Qualify: 0
Keepalive: 0
Use ClientCode: No
Progress inband: No
Language: en
Tone zone:
MOH Interpret: default
MOH Suggest:
Voice Mail Extension: *97