We are running FreePBX 13 Fully updated running in CyberLynk as a dedicated server. Currently we have 200 Phones 130 at one site and 70 phones at another site. I would say about 1 a minute at either site will have a phone lag and be unreachable for 20 or so seconds with a qualify above 2000 ms. Both sites have a sonicwall NSA 4600 (barely being utilized) with 100/100Mbps fiber connection. As a test I did follow this article https://pbxinaflash.com/community/threads/former-sonicwall-resource-by-hbonath.12549/ to deploy QoS on the firewall with 130 clients and might have helped phone are still lagging out. Currently Voice is not separated from data traffic and will look into that.
Questions, Does QoS need to deployed on the switches or everything being handled by FreePBX and the sonicwall? Anyone have any other idea where to look at?
Logs
[2017-03-30 11:03:50] NOTICE[3039] chan_sip.c: Peer ‘4176’ is now Lagged. (2051ms / 2000ms)
[2017-03-30 11:03:50] VERBOSE[31395][C-00003340] bridge_channel.c: Channel SIP/4854-0000fa50 left ‘simple_bridge’ basic-bridge <21544fc1-c4dd-43c3-9684-c99da97b5aaf>
[2017-03-30 11:03:50] VERBOSE[31445][C-00003340] bridge_channel.c: Channel SIP/VitelOBTrunk-0000fa51 left ‘simple_bridge’ basic-bridge <21544fc1-c4dd-43c3-9684-c99da97b5aaf>
[2017-03-30 11:03:50] VERBOSE[31395][C-00003340] app_macro.c: Spawn extension (macro-dialout-trunk, s, 23) exited non-zero on ‘SIP/4854-0000fa50’ in macro ‘dialout-trunk’
[2017-03-30 11:03:50] VERBOSE[31395][C-00003340] pbx.c: Spawn extension (restrictedroute-c4ca4238a0b923820dcc509a6f75849b, 93214593618, 7) exited non-zero on ‘SIP/4854-0000fa50’
[2017-03-30 11:03:50] VERBOSE[31395][C-00003340] pbx.c: Executing [h@restrictedroute-c4ca4238a0b923820dcc509a6f75849b:1] Hangup(“SIP/4854-0000fa50”, “”) in new stack
[2017-03-30 11:03:50] VERBOSE[31395][C-00003340] pbx.c: Spawn extension (restrictedroute-c4ca4238a0b923820dcc509a6f75849b, h, 1) exited non-zero on ‘SIP/4854-0000fa50’
[2017-03-30 11:03:50] VERBOSE[2996] chan_sip.c: Extension Changed 4854[ext-local] new state Idle for Notify User 4749
[2017-03-30 11:03:50] VERBOSE[2996] chan_sip.c: Extension Changed 4854[ext-local] new state Idle for Notify User 4828
[2017-03-30 11:03:50] VERBOSE[2996] chan_sip.c: Extension Changed 4854[ext-local] new state Idle for Notify User 4755
[2017-03-30 11:03:50] VERBOSE[2996] chan_sip.c: Extension Changed 4854[ext-local] new state Idle for Notify User 3035
[2017-03-30 11:03:50] VERBOSE[2996] chan_sip.c: Extension Changed 4854[ext-local] new state Idle for Notify User 3005
[2017-03-30 11:03:50] VERBOSE[2996] chan_sip.c: Extension Changed 4854[ext-local] new state Idle for Notify User 2000
[2017-03-30 11:03:50] VERBOSE[2996] chan_sip.c: Extension Changed 4854[ext-local] new state Idle for Notify User 3036
[2017-03-30 11:03:50] VERBOSE[2996] chan_sip.c: Extension Changed 4854[ext-local] new state Idle for Notify User 4412
[2017-03-30 11:03:50] VERBOSE[2996] chan_sip.c: Extension Changed 4854[ext-local] new state Idle for Notify User 4809
[2017-03-30 11:03:50] VERBOSE[2996] chan_sip.c: Extension Changed 4854[ext-local] new state Idle for Notify User 4759
[2017-03-30 11:03:50] VERBOSE[2996] chan_sip.c: Extension Changed 4854[ext-local] new state Idle for Notify User 4189
[2017-03-30 11:03:50] VERBOSE[2996] chan_sip.c: Extension Changed 4854[ext-local] new state Idle for Notify User 4175
[2017-03-30 11:03:50] VERBOSE[2996] chan_sip.c: Extension Changed 4854[ext-local] new state Idle for Notify User 4829
[2017-03-30 11:03:50] VERBOSE[2996] chan_sip.c: Extension Changed 4854[ext-local] new state Idle for Notify User 4184
[2017-03-30 11:03:50] VERBOSE[2996] chan_sip.c: Extension Changed 4854[ext-local] new state Idle for Notify User 4424
[2017-03-30 11:03:52] VERBOSE[31499][C-00003348] app_macro.c: Spawn extension (macro-vm, s-BUSY, 3) exited non-zero on ‘SIP/4764-0000fa69’ in macro ‘vm’
[2017-03-30 11:03:52] VERBOSE[31499][C-00003348] pbx.c: Spawn extension (ext-local, 4813, 4) exited non-zero on ‘SIP/4764-0000fa69’
[2017-03-30 11:03:52] VERBOSE[31499][C-00003348] pbx.c: Executing [h@ext-local:1] Macro(“SIP/4764-0000fa69”, “hangupcall,”) in new stack
[2017-03-30 11:03:52] VERBOSE[31499][C-00003348] pbx.c: Executing [s@macro-hangupcall:1] GotoIf(“SIP/4764-0000fa69”, “1?theend”) in new stack
[2017-03-30 11:03:52] VERBOSE[31499][C-00003348] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2017-03-30 11:03:52] VERBOSE[31499][C-00003348] pbx.c: Executing [s@macro-hangupcall:3] ExecIf(“SIP/4764-0000fa69”, “0?Set(CDR(recordingfile)=)”) in new stack
[2017-03-30 11:03:52] VERBOSE[31499][C-00003348] pbx.c: Executing [s@macro-hangupcall:4] Hangup(“SIP/4764-0000fa69”, “”) in new stack
[2017-03-30 11:03:52] VERBOSE[31499][C-00003348] app_macro.c: Spawn extension (macro-hangupcall, s, 4) exited non-zero on ‘SIP/4764-0000fa69’ in macro ‘hangupcall’
[2017-03-30 11:03:52] VERBOSE[31499][C-00003348] pbx.c: Spawn extension (ext-local, h, 1) exited non-zero on ‘SIP/4764-0000fa69’
[2017-03-30 11:03:52] VERBOSE[31489][C-00003346] app_macro.c: Spawn extension (macro-dial, s, 19) exited non-zero on ‘SIP/4198-0000fa5a’ in macro ‘dial’
[2017-03-30 11:03:52] VERBOSE[31489][C-00003346] pbx.c: Spawn extension (followme-sub, 4190, 39) exited non-zero on ‘SIP/4198-0000fa5a’
[2017-03-30 11:03:52] VERBOSE[2996] chan_sip.c: Extension Changed 4190[ext-local] new state InUse for Notify User 2000
[2017-03-30 11:03:52] VERBOSE[2996] chan_sip.c: Extension Changed 4190[ext-local] new state InUse for Notify User 3005
[2017-03-30 11:03:52] VERBOSE[2996] chan_sip.c: Extension Changed 4190[ext-local] new state InUse for Notify User 4755
[2017-03-30 11:03:52] VERBOSE[2996] chan_sip.c: Extension Changed 4190[ext-local] new state InUse for Notify User 4759
[2017-03-30 11:03:52] VERBOSE[2996] chan_sip.c: Extension Changed 4190[ext-local] new state InUse for Notify User 4189
[2017-03-30 11:03:52] VERBOSE[2996] chan_sip.c: Extension Changed 4190[ext-local] new state InUse for Notify User 4184
[2017-03-30 11:03:52] VERBOSE[2996] chan_sip.c: Extension Changed 4190[ext-local] new state InUse for Notify User 4749
[2017-03-30 11:03:52] VERBOSE[2996] chan_sip.c: Extension Changed 4190[ext-local] new state InUse for Notify User 4809
[2017-03-30 11:03:52] VERBOSE[2996] chan_sip.c: Extension Changed 4190[ext-local] new state InUse for Notify User 4829
[2017-03-30 11:03:52] VERBOSE[2996] chan_sip.c: Extension Changed 4190[ext-local] new state InUse for Notify User 4828
[2017-03-30 11:03:52] VERBOSE[2996] chan_sip.c: Extension Changed 4190[ext-local] new state InUse for Notify User 3036
[2017-03-30 11:03:52] VERBOSE[2996] chan_sip.c: Extension Changed 4190[ext-local] new state InUse for Notify User 4175
[2017-03-30 11:03:52] VERBOSE[2996] chan_sip.c: Extension Changed 4190[ext-local] new state InUse for Notify User 4424
[2017-03-30 11:03:52] VERBOSE[2996] chan_sip.c: Extension Changed 4190[ext-local] new state InUse for Notify User 4412
[2017-03-30 11:03:52] VERBOSE[2996] chan_sip.c: Extension Changed 4190[ext-local] new state InUse for Notify User 3035
[2017-03-30 11:03:52] SECURITY[3048] res_security_log.c: SecurityEvent=“ChallengeSent”,EventTV=“2017-03-30T11:03:52.752-0400”,Severity=“Informational”,Service=“SIP”,EventVersion=“1”,AccountID="sip:[email protected]",SessionID=“0x7f0e04b25200”,LocalAddress=“IPV4/UDP/66.185.29.10/5060”,RemoteAddress=“IPV4/UDP/12.226.144.105/21539”,Challenge=“4bcacc6a”
[2017-03-30 11:03:52] VERBOSE[3039][C-00003349] netsock2.c: Using SIP RTP TOS bits 184
[2017-03-30 11:03:52] VERBOSE[3039][C-00003349] netsock2.c: Using SIP RTP CoS mark 5
[2017-03-30 11:03:52] SECURITY[3048] res_security_log.c: SecurityEvent=“SuccessfulAuth”,EventTV=“2017-03-30T11:03:52.922-0400”,Severity=“Informational”,Service=“SIP”,EventVersion=“1”,AccountID=“917812900808”,SessionID=“0x7f0e04b25200”,LocalAddress=“IPV4/UDP/66.185.29.10/5060”,RemoteAddress=“IPV4/UDP/12.226.144.105/21539”,UsingPassword=“1”
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [917812900808@from-internal:1] Macro(“SIP/3020-0000fa6a”, “user-callerid,LIMIT”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-user-callerid:1] Set(“SIP/3020-0000fa6a”, “TOUCH_MONITOR=1490886232.69072”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-user-callerid:2] Set(“SIP/3020-0000fa6a”, “AMPUSER=3020”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-user-callerid:3] GotoIf(“SIP/3020-0000fa6a”, “0?report”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-user-callerid:4] ExecIf(“SIP/3020-0000fa6a”, “1?Set(REALCALLERIDNUM=3020)”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-user-callerid:5] Set(“SIP/3020-0000fa6a”, “AMPUSER=3020”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-user-callerid:6] GotoIf(“SIP/3020-0000fa6a”, “0?limit”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-user-callerid:7] Set(“SIP/3020-0000fa6a”, “AMPUSERCIDNAME=Antonio Forney”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-user-callerid:8] GotoIf(“SIP/3020-0000fa6a”, “0?report”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-user-callerid:9] Set(“SIP/3020-0000fa6a”, “AMPUSERCID=3020”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-user-callerid:10] Set(“SIP/3020-0000fa6a”, “__DIAL_OPTIONS=Ttr”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-user-callerid:11] Set(“SIP/3020-0000fa6a”, “CALLERID(all)=“Antonio Forney” <3020>”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-user-callerid:12] GotoIf(“SIP/3020-0000fa6a”, “0?limit”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-user-callerid:13] ExecIf(“SIP/3020-0000fa6a”, “1?Set(GROUP(concurrency_limit)=3020)”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-user-callerid:14] ExecIf(“SIP/3020-0000fa6a”, “0?Set(CHANNEL(language)=)”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-user-callerid:15] GotoIf(“SIP/3020-0000fa6a”, “1?continue”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx_builtins.c: Goto (macro-user-callerid,s,29)
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-user-callerid:29] Set(“SIP/3020-0000fa6a”, “CALLERID(number)=3020”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-user-callerid:30] Set(“SIP/3020-0000fa6a”, “CALLERID(name)=Antonio Forney”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-user-callerid:31] GotoIf(“SIP/3020-0000fa6a”, “0?cnum”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-user-callerid:32] Set(“SIP/3020-0000fa6a”, “CDR(cnam)=Antonio Forney”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-user-callerid:33] Set(“SIP/3020-0000fa6a”, “CDR(cnum)=3020”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-user-callerid:34] Set(“SIP/3020-0000fa6a”, “CHANNEL(language)=en”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-user-callerid:35] GosubIf(“SIP/3020-0000fa6a”, “0?app-check-classofservce,s,1()”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [917812900808@from-internal:2] Set(“SIP/3020-0000fa6a”, “ROUTEUSER=3020”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [917812900808@from-internal:3] GotoIf(“SIP/3020-0000fa6a”, “1?notblind”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx_builtins.c: Goto (from-internal,917812900808,6)
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [917812900808@from-internal:6] GotoIf(“SIP/3020-0000fa6a”, “1?restrictedroute-c4ca4238a0b923820dcc509a6f75849b,917812900808,2:outbound-allroutes,917812900808,2”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx_builtins.c: Goto (restrictedroute-c4ca4238a0b923820dcc509a6f75849b,917812900808,2)
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [917812900808@restrictedroute-c4ca4238a0b923820dcc509a6f75849b:2] Gosub(“SIP/3020-0000fa6a”, “sub-record-check,s,1(out,917812900808,no)”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@sub-record-check:1] GotoIf(“SIP/3020-0000fa6a”, “0?initialized”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@sub-record-check:2] Set(“SIP/3020-0000fa6a”, “__REC_STATUS=INITIALIZED”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@sub-record-check:3] Set(“SIP/3020-0000fa6a”, “NOW=1490886232”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@sub-record-check:4] Set(“SIP/3020-0000fa6a”, “__DAY=30”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@sub-record-check:5] Set(“SIP/3020-0000fa6a”, “__MONTH=03”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@sub-record-check:6] Set(“SIP/3020-0000fa6a”, “__YEAR=2017”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@sub-record-check:7] Set(“SIP/3020-0000fa6a”, “__TIMESTR=20170330-110352”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@sub-record-check:8] Set(“SIP/3020-0000fa6a”, “__FROMEXTEN=3020”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@sub-record-check:9] Set(“SIP/3020-0000fa6a”, “__MON_FMT=wav”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@sub-record-check:10] NoOp(“SIP/3020-0000fa6a”, “Recordings initialized”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@sub-record-check:11] ExecIf(“SIP/3020-0000fa6a”, “0?Set(ARG3=dontcare)”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@sub-record-check:12] Set(“SIP/3020-0000fa6a”, “REC_POLICY_MODE_SAVE=”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@sub-record-check:13] ExecIf(“SIP/3020-0000fa6a”, “0?Set(REC_STATUS=NO)”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@sub-record-check:14] GotoIf(“SIP/3020-0000fa6a”, “3?checkaction”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx_builtins.c: Goto (sub-record-check,s,17)
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@sub-record-check:17] GotoIf(“SIP/3020-0000fa6a”, “1?sub-record-check,out,1”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx_builtins.c: Goto (sub-record-check,out,1)
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [out@sub-record-check:1] NoOp(“SIP/3020-0000fa6a”, “Outbound Recording Check from 3020 to 917812900808”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [out@sub-record-check:2] Set(“SIP/3020-0000fa6a”, “RECMODE=no”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [out@sub-record-check:3] ExecIf(“SIP/3020-0000fa6a”, “0?Goto(routewins)”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [out@sub-record-check:4] ExecIf(“SIP/3020-0000fa6a”, “0?Goto(routewins)”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [out@sub-record-check:5] Gosub(“SIP/3020-0000fa6a”, “recordcheck,1(no,out,917812900808)”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp(“SIP/3020-0000fa6a”, “Starting recording check against no”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [recordcheck@sub-record-check:2] Goto(“SIP/3020-0000fa6a”, “no”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx_builtins.c: Goto (sub-record-check,recordcheck,12)
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [recordcheck@sub-record-check:12] Set(“SIP/3020-0000fa6a”, “__REC_POLICY_MODE=NO”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [recordcheck@sub-record-check:13] Return(“SIP/3020-0000fa6a”, “”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [out@sub-record-check:6] Return(“SIP/3020-0000fa6a”, “”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [917812900808@restrictedroute-c4ca4238a0b923820dcc509a6f75849b:3] ExecIf(“SIP/3020-0000fa6a”, “0 ?Set(CDR(accountcode)=)”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [917812900808@restrictedroute-c4ca4238a0b923820dcc509a6f75849b:4] Set(“SIP/3020-0000fa6a”, “MOHCLASS=default”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [917812900808@restrictedroute-c4ca4238a0b923820dcc509a6f75849b:5] ExecIf(“SIP/3020-0000fa6a”, “1?Set(TRUNKCIDOVERRIDE=3213974420)”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [917812900808@restrictedroute-c4ca4238a0b923820dcc509a6f75849b:6] Set(“SIP/3020-0000fa6a”, “_NODEST=”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [917812900808@restrictedroute-c4ca4238a0b923820dcc509a6f75849b:7] Macro(“SIP/3020-0000fa6a”, “dialout-trunk,3,17812900808,off”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-dialout-trunk:1] Set(“SIP/3020-0000fa6a”, “DIAL_TRUNK=3”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-dialout-trunk:2] GosubIf(“SIP/3020-0000fa6a”, “0?sub-pincheck,s,1()”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-dialout-trunk:3] GotoIf(“SIP/3020-0000fa6a”, “0?disabletrunk,1”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-dialout-trunk:4] Set(“SIP/3020-0000fa6a”, “DIAL_NUMBER=17812900808”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-dialout-trunk:5] Set(“SIP/3020-0000fa6a”, “DIAL_TRUNK_OPTIONS=Ttr”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-dialout-trunk:6] Set(“SIP/3020-0000fa6a”, “OUTBOUND_GROUP=OUT_3”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-dialout-trunk:7] GotoIf(“SIP/3020-0000fa6a”, “0?nomax”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-dialout-trunk:8] GotoIf(“SIP/3020-0000fa6a”, “0?chanfull”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-dialout-trunk:9] GotoIf(“SIP/3020-0000fa6a”, “0?skipoutcid”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-dialout-trunk:10] Set(“SIP/3020-0000fa6a”, “DIAL_TRUNK_OPTIONS=T”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-dialout-trunk:11] Macro(“SIP/3020-0000fa6a”, “outbound-callerid,3”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-outbound-callerid:1] ExecIf(“SIP/3020-0000fa6a”, “0?Set(CALLERPRES(name-pres)=)”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-outbound-callerid:2] ExecIf(“SIP/3020-0000fa6a”, “0?Set(CALLERPRES(num-pres)=)”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-outbound-callerid:3] ExecIf(“SIP/3020-0000fa6a”, “0?Set(REALCALLERIDNUM=3020)”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-outbound-callerid:4] GotoIf(“SIP/3020-0000fa6a”, “1?normcid”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx_builtins.c: Goto (macro-outbound-callerid,s,7)
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-outbound-callerid:7] Set(“SIP/3020-0000fa6a”, “USEROUTCID=”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-outbound-callerid:8] Set(“SIP/3020-0000fa6a”, “EMERGENCYCID=”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-outbound-callerid:9] Set(“SIP/3020-0000fa6a”, “TRUNKOUTCID=3213974420”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-outbound-callerid:10] GotoIf(“SIP/3020-0000fa6a”, “1?trunkcid”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx_builtins.c: Goto (macro-outbound-callerid,s,15)
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-outbound-callerid:15] ExecIf(“SIP/3020-0000fa6a”, “1?Set(CALLERID(all)=3213974420)”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-outbound-callerid:16] ExecIf(“SIP/3020-0000fa6a”, “0?Set(CALLERID(all)=)”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-outbound-callerid:17] ExecIf(“SIP/3020-0000fa6a”, “1?Set(CALLERID(all)=3213974420)”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-outbound-callerid:18] ExecIf(“SIP/3020-0000fa6a”, “0?Set(CALLERPRES(name-pres)=prohib_passed_screen)”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-outbound-callerid:19] ExecIf(“SIP/3020-0000fa6a”, “0?Set(CALLERPRES(num-pres)=prohib_passed_screen)”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-outbound-callerid:20] Set(“SIP/3020-0000fa6a”, “CDR(outbound_cnum)=3213974420”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-outbound-callerid:21] Set(“SIP/3020-0000fa6a”, “CDR(outbound_cnam)=”) in new stack
[2017-03-30 11:03:52] WARNING[2995] func_cdr.c: CDR requires a value (CDR(variable)=value)
)[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-dialout-trunk:12] GosubIf(“SIP/3020-0000fa6a”, “0?sub-flp-3,s,1()”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-dialout-trunk:13] Set(“SIP/3020-0000fa6a”, “OUTNUM=17812900808”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-dialout-trunk:14] Set(“SIP/3020-0000fa6a”, “custom=SIP/VitelOBTrunk”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-dialout-trunk:15] ExecIf(“SIP/3020-0000fa6a”, “0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)T)”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-dialout-trunk:16] ExecIf(“SIP/3020-0000fa6a”, “0?Set(DIAL_TRUNK_OPTIONS=TM(confirm))”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-dialout-trunk:17] Macro(“SIP/3020-0000fa6a”, “dialout-trunk-predial-hook,”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit(“SIP/3020-0000fa6a”, “”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-dialout-trunk:18] GotoIf(“SIP/3020-0000fa6a”, “0?bypass,1”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-dialout-trunk:19] ExecIf(“SIP/3020-0000fa6a”, “1?Set(CONNECTEDLINE(num,i)=17812900808)”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-dialout-trunk:20] ExecIf(“SIP/3020-0000fa6a”, “1?Set(CONNECTEDLINE(name,i)=CID:3213974420)”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-dialout-trunk:21] ExecIf(“SIP/3020-0000fa6a”, “0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)3213974420)”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-dialout-trunk:22] GotoIf(“SIP/3020-0000fa6a”, “0?customtrunk”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] pbx.c: Executing [s@macro-dialout-trunk:23] Dial(“SIP/3020-0000fa6a”, “SIP/VitelOBTrunk/17812900808,300,T”) in new stack
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] netsock2.c: Using SIP RTP TOS bits 184
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] netsock2.c: Using SIP RTP CoS mark 5
[2017-03-30 11:03:52] VERBOSE[31527][C-00003349] app_dial.c: Called SIP/VitelOBTrunk/17812900808
[2017-03-30 11:03:53] VERBOSE[31496][C-00003347] app_dial.c: SIP/4809-0000fa5d connected line has changed. Saving it until answer for SIP/Vitelity-In-0000fa5c
[2017-03-30 11:03:53] VERBOSE[31496][C-00003347] app_dial.c: SIP/4809-0000fa5d answered SIP/Vitelity-In-0000fa5c
[2017-03-30 11:03:53] VERBOSE[31496][C-00003347] pbx.c: Executing [s@macro-auto-blkvm:1] Set(“SIP/4809-0000fa5d”, “__MACRO_RESULT=”) in new stack
[2017-03-30 11:03:53] VERBOSE[31496][C-00003347] pbx.c: Executing [s@macro-auto-blkvm:2] Set(“SIP/4809-0000fa5d”, “CFIGNORE=”) in new stack
[2017-03-30 11:03:53] VERBOSE[31496][C-00003347] pbx.c: Executing [s@macro-auto-blkvm:3] Set(“SIP/4809-0000fa5d”, “MASTER_CHANNEL(CFIGNORE)=”) in new stack
[2017-03-30 11:03:53] VERBOSE[31496][C-00003347] pbx.c: Executing [s@macro-auto-blkvm:4] Set(“SIP/4809-0000fa5d”, “FORWARD_CONTEXT=from-internal”) in new stack
[2017-03-30 11:03:53] VERBOSE[31496][C-00003347] pbx.c: Executing [s@macro-auto-blkvm:5] Set(“SIP/4809-0000fa5d”, “MASTER_CHANNEL(FORWARD_CONTEXT)=from-internal”) in new stack
[2017-03-30 11:03:53] VERBOSE[31496][C-00003347] pbx.c: Executing [s@macro-auto-blkvm:6] Macro(“SIP/4809-0000fa5d”, “blkvm-clr,”) in new stack
[2017-03-30 11:03:53] VERBOSE[31496][C-00003347] pbx.c: Executing [s@macro-blkvm-clr:1] Set(“SIP/4809-0000fa5d”, “SHARED(BLKVM,SIP/Vitelity-In-0000fa5c)=”) in new stack
[2017-03-30 11:03:53] VERBOSE[31496][C-00003347] pbx.c: Executing [s@macro-blkvm-clr:2] Set(“SIP/4809-0000fa5d”, “GOSUB_RETVAL=”) in new stack
[2017-03-30 11:03:53] VERBOSE[31496][C-00003347] pbx.c: Executing [s@macro-blkvm-clr:3] MacroExit(“SIP/4809-0000fa5d”, “”) in new stack
[2017-03-30 11:03:53] VERBOSE[31496][C-00003347] pbx.c: Executing [s@macro-auto-blkvm:7] ExecIf(“SIP/4809-0000fa5d”, “0?Set(MASTER_CHANNEL(CONNECTEDLINE(num))=4809)”) in new stack
[2017-03-30 11:03:53] VERBOSE[31496][C-00003347] pbx.c: Executing [s@macro-auto-blkvm:8] ExecIf(“SIP/4809-0000fa5d”, “0?Set(MASTER_CHANNEL(CONNECTEDLINE(name))=Yahaira Martins)”) in new stack
[2017-03-30 11:03:53] VERBOSE[31535][C-00003347] bridge_channel.c: Channel SIP/4809-0000fa5d joined ‘simple_bridge’ basic-bridge
[2017-03-30 11:03:53] VERBOSE[31496][C-00003347] bridge_channel.c: Channel SIP/Vitelity-In-0000fa5c joined ‘simple_bridge’ basic-bridge
[2017-03-30 11:03:54] VERBOSE[31527][C-00003349] app_dial.c: SIP/VitelOBTrunk-0000fa6b is ringing
[2017-03-30 11:03:54] VERBOSE[31527][C-00003349] app_dial.c: SIP/VitelOBTrunk-0000fa6b is making progress passing it to SIP/3020-0000fa6a
[2017-03-30 11:03:55] NOTICE[3039] chan_sip.c: Peer ‘4795’ is now Reachable. (1057ms / 2000ms)
[2017-03-30 11:03:57] VERBOSE[31486][C-00003344] app_dial.c: SIP/VitelOBTrunk-0000fa58 answered SIP/4190-0000fa57
[2017-03-30 11:03:57] VERBOSE[31536][C-00003344] bridge_channel.c: Channel SIP/VitelOBTrunk-0000fa58 joined ‘simple_bridge’ basic-bridge <0934d170-6dda-4c31-94de-8361f94b6f75>
[2017-03-30 11:03:57] VERBOSE[31486][C-00003344] bridge_channel.c: Channel SIP/4190-0000fa57 joined ‘simple_bridge’ basic-bridge <0934d170-6dda-4c31-94de-8361f94b6f75>
[2017-03-30 11:03:57] VERBOSE[31527][C-00003349] app_dial.c: SIP/VitelOBTrunk-0000fa6b answered SIP/3020-0000fa6a
[2017-03-30 11:03:57] VERBOSE[31537][C-00003349] bridge_channel.c: Channel SIP/VitelOBTrunk-0000fa6b joined ‘simple_bridge’ basic-bridge <5ed78304-a4a8-4964-ae82-46f816b9bd4a>
[2017-03-30 11:03:57] VERBOSE[31527][C-00003349] bridge_channel.c: Channel SIP/3020-0000fa6a joined ‘simple_bridge’ basic-bridge <5ed78304-a4a8-4964-ae82-46f816b9bd4a>
[2017-03-30 11:03:58] SECURITY[3048] res_security_log.c: SecurityEvent=“ChallengeSent”,EventTV=“2017-03-30T11:03:58.743-0400”,Severity=“Informational”,Service=“SIP”,EventVersion=“1”,AccountID=“4791”,SessionID=“0x7f0e046b85b0”,LocalAddress=“IPV4/UDP/66.185.29.10/5060”,RemoteAddress=“IPV4/UDP/50.59.222.206/23727”,Challenge=“7d9f78d8”
[2017-03-30 11:03:58] SECURITY[3048] res_security_log.c: SecurityEvent=“SuccessfulAuth”,EventTV=“2017-03-30T11:03:58.805-0400”,Severity=“Informational”,Service=“SIP”,EventVersion=“1”,AccountID=“4791”,SessionID=“0x7f0e046b85b0”,LocalAddress=“IPV4/UDP/66.185.29.10/5060”,RemoteAddress=“IPV4/UDP/50.59.222.206/23727”,UsingPassword=“1”
[2017-03-30 11:03:59] WARNING[3039] chan_sip.c: Retransmission timeout reached on transmission [email protected] for seqno 102 (Critical Response) – See https://wiki.asterisk.org/wiki/display/AST/SIP+Retransmissions
Packet timed out after 6400ms with no response
[2017-03-30 11:03:59] WARNING[3039] chan_sip.c: Hanging up call [email protected] - no reply to our critical packet (see https://wiki.asterisk.org/wiki/display/AST/SIP+Retransmissions).
[2017-03-30 11:03:59] VERBOSE[31496][C-00003347] bridge_channel.c: Channel SIP/Vitelity-In-0000fa5c left ‘simple_bridge’ basic-bridge
[2017-03-30 11:03:59] VERBOSE[31496][C-00003347] app_macro.c: Spawn extension (macro-dial, s, 19) exited non-zero on ‘SIP/Vitelity-In-0000fa5c’ in macro ‘dial’
[2017-03-30 11:03:59] VERBOSE[31496][C-00003347] pbx.c: Spawn extension (ext-group, 609, 14) exited non-zero on ‘SIP/Vitelity-In-0000fa5c’
[2017-03-30 11:03:59] VERBOSE[31496][C-00003347] pbx.c: Executing [h@ext-group:1] Macro(“SIP/Vitelity-In-0000fa5c”, “hangupcall,”) in new stack
[2017-03-30 11:03:59] VERBOSE[31496][C-00003347] pbx.c: Executing [s@macro-hangupcall:1] GotoIf(“SIP/Vitelity-In-0000fa5c”, “1?theend”) in new stack
[2017-03-30 11:03:59] VERBOSE[31496][C-00003347] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2017-03-30 11:03:59] VERBOSE[31535][C-00003347] bridge_channel.c: Channel SIP/4809-0000fa5d left ‘simple_bridge’ basic-bridge
[2017-03-30 11:03:59] VERBOSE[31496][C-00003347] pbx.c: Executing [s@macro-hangupcall:3] ExecIf(“SIP/Vitelity-In-0000fa5c”, “0?Set(CDR(recordingfile)=)”) in new stack
[2017-03-30 11:03:59] VERBOSE[31496][C-00003347] pbx.c: Executing [s@macro-hangupcall:4] Hangup(“SIP/Vitelity-In-0000fa5c”, “”) in new stack
[2017-03-30 11:03:59] VERBOSE[31496][C-00003347] app_macro.c: Spawn extension (macro-hangupcall, s, 4) exited non-zero on ‘SIP/Vitelity-In-0000fa5c’ in macro ‘hangupcall’
[2017-03-30 11:03:59] VERBOSE[31496][C-00003347] pbx.c: Spawn extension (ext-group, h, 1) exited non-zero on ‘SIP/Vitelity-In-0000fa5c’
[2017-03-30 11:04:00] NOTICE[3039] chan_sip.c: Peer ‘4176’ is now Reachable. (53ms / 2000ms)