Ring group problem

Have a strange thing what happens
1: Internal extensions are calling a Misc Application number
2: Misc Applicaction destination is Time Conditions
3: Time Conditions checks Match ok or non Match
4: Both matches go to a ring group with 2 extensions
What happens is silence some times, you can make 10 calls without any problem. Then suddenly only thing what happens is Silence (phones do nothing) and if you wait long enough 1 or 2 minutes it starts ringing again.
External calls also arives at the time conditions, there we have no issues.Also happens by direct dial internal the ring group.

I changed a lot of group settings, with parallel, Audio, ring, Announcement nothing helps so far

Without call logs, there is no possible way to help.

Logging is to much to share here, is there another way?

Call from extension 602 to misc destination 711. 711 is calling ring group 694 with extension 603 in it. Made 4 Calls, first 3 are ok. The 4 one gives ringback tone on 602, but extension 603 does nothing after a minute it starts ringing. Printscreen from the Pcap

“Start Time”,“Stop Time”,“Initial Speaker”,“From”,“To”,“Protocol”,“Duration”,“Packets”,“State”,“Comments”
“3.082786”,“12.262544”,“1xx.xxx.xxx.xxx”,"“Martijn” <sip:[email protected]","<sip:[email protected];user=phone",“SIP”,“00:00:09”,“10”,“CANCELLED”,“INVITE 401 487 200"
“14.029973”,“19.889675”,“1xx.xxx.xxx.xxx”,”“Martijn” <sip:[email protected]","<sip:[email protected];user=phone",“SIP”,“00:00:05”,“10”,“CANCELLED”,“INVITE 401 487 200"
“22.151141”,“26.972300”,“1xx.xxx.xxx.xxx”,”“Martijn” <sip:[email protected]","<sip:[email protected];user=phone",“SIP”,“00:00:04”,“10”,“CANCELLED”,“INVITE 401 487 200"
“28.719679”,“34.132034”,“1xx.xxx.xxx.xxx”,”“Martijn” <sip:[email protected]","<sip:[email protected];user=phone",“SIP”,“00:00:05”,“10”,“CANCELLED”,“INVITE 401 487 200"
“36.244520”,“40.199269”,“1xx.xxx.xxx.xxx”,”“Martijn” <sip:[email protected]","<sip:[email protected];user=phone",“SIP”,“00:00:03”,“10”,“CANCELLED”,“INVITE 401 487 200"
“41.799396”,“52.171264”,“1xx.xxx.xxx.xxx”,”“Martijn” <sip:[email protected]","<sip:[email protected];user=phone",“SIP”,“00:00:10”,“12”,“COMPLETED”,“INVITE 401 200"
“43.240052”,“52.244013”,“2xx.xxx.xxx.xxx”,”“Martijn” <sip:[email protected]","<sip:[email protected]:5062",“SIP”,“00:00:09”,“7”,“COMPLETED”,“INVITE 200"
“54.621793”,“61.291211”,“1xx.xxx.xxx.xxx”,”“Martijn” <sip:[email protected]","<sip:[email protected];user=phone",“SIP”,“00:00:06”,“12”,“COMPLETED”,“INVITE 401 200"
“55.338291”,“61.321759”,“2xx.xxx.xxx.xxx”,”“Martijn” <sip:[email protected]","<sip:[email protected]:5062",“SIP”,“00:00:05”,“7”,“COMPLETED”,“INVITE 200"
“63.554002”,“71.078112”,“1xx.xxx.xxx.xxx”,”“Martijn” <sip:[email protected]","<sip:[email protected];user=phone",“SIP”,“00:00:07”,“12”,“COMPLETED”,“INVITE 401 200"
“64.193513”,“71.118609”,“2xx.xxx.xxx.xxx”,”“Martijn” <sip:[email protected]","<sip:[email protected]:5062",“SIP”,“00:00:06”,“7”,“COMPLETED”,“INVITE 200"
“73.433813”,“78.633527”,“1xx.xxx.xxx.xxx”,”“Martijn” <sip:[email protected]","<sip:[email protected];user=phone",“SIP”,“00:00:05”,“12”,“COMPLETED”,“INVITE 401 200"
“74.179086”,“78.668516”,“2xx.xxx.xxx.xxx”,”“Martijn” <sip:[email protected]","<sip:[email protected]:5062",“SIP”,“00:00:04”,“7”,“COMPLETED”,“INVITE 200"
“80.991312”,“91.213977”,“1xx.xxx.xxx.xxx”,”“Martijn” <sip:[email protected]","<sip:[email protected];user=phone",“SIP”,“00:00:10”,“12”,“COMPLETED”,“INVITE 401 200"
“81.652203”,“91.253266”,“2xx.xxx.xxx.xxx”,”“Martijn” <sip:[email protected]","<sip:[email protected]:5062",“SIP”,“00:00:09”,“15”,“COMPLETED”,“INVITE 200"
“93.993413”,“100.083022”,“1xx.xxx.xxx.xxx”,”“Martijn” <sip:[email protected]","<sip:[email protected];user=phone",“SIP”,“00:00:06”,“12”,“COMPLETED”,“INVITE 401 200"
“94.772489”,“100.120839”,“2xx.xxx.xxx.xxx”,”“Martijn” <sip:[email protected]","<sip:[email protected]:5062",“SIP”,“00:00:05”,“7”,“COMPLETED”,“INVITE 200"
“101.948257”,“108.270013”,“1xx.xxx.xxx.xxx”,”“Martijn” <sip:[email protected]","<sip:[email protected];user=phone",“SIP”,“00:00:06”,“12”,“COMPLETED”,“INVITE 401 200"
“102.598244”,“108.302918”,“2xx.xxx.xxx.xxx”,”“Martijn” <sip:[email protected]","<sip:[email protected]:5062",“SIP”,“00:00:05”,“7”,“COMPLETED”,“INVITE 200"
“110.213100”,“115.840653”,“1xx.xxx.xxx.xxx”,”“Martijn” <sip:[email protected]","<sip:[email protected];user=phone",“SIP”,“00:00:05”,“12”,“COMPLETED”,“INVITE 401 200"
“110.965089”,“115.877796”,“2xx.xxx.xxx.xxx”,”“Martijn” <sip:[email protected]","<sip:[email protected]:5062",“SIP”,“00:00:04”,“7”,“COMPLETED”,“INVITE 200"
“117.602949”,“129.610175”,“1xx.xxx.xxx.xxx”,”“Martijn” <sip:[email protected]","<sip:[email protected];user=phone",“SIP”,“00:00:12”,“10”,“CANCELLED”,“INVITE 401 487 200”

What’s wrong with using the /var/log/asterisk/full log? This tells us there’s network traffic, but nothing about what the PBX is doing with your calls or why there are errors.

A better method :-

https://wiki.freepbx.org/display/SUP/Providing+Great+Debug

1 Like

Call this morning one time see logging this is form the Full log

[2017-10-25 08:21:39] VERBOSE[6614][C-0000025e] pbx.c:     -- Executing [711@from-internal:1] NoOp("SIP/602-00000445", "Running miscapp 6: Tes Martijn Inkomend") in new stack
[2017-10-25 08:21:39] VERBOSE[6614][C-0000025e] pbx.c:     -- Executing [711@from-internal:2] Macro("SIP/602-00000445", "user-callerid,") in new stack
[2017-10-25 08:21:39] VERBOSE[6614][C-0000025e] pbx.c:     -- Executing [s@macro-user-callerid:1] Set("SIP/602-00000445", "TOUCH_MONITOR=1508912499.1227") in new stack
[2017-10-25 08:21:39] VERBOSE[6614][C-0000025e] pbx.c:     -- Executing [s@macro-user-callerid:2] Set("SIP/602-00000445", "AMPUSER=602") in new stack
[2017-10-25 08:21:39] VERBOSE[6614][C-0000025e] pbx.c:     -- Executing [s@macro-user-callerid:3] GotoIf("SIP/602-00000445", "0?report") in new stack
[2017-10-25 08:21:39] VERBOSE[6614][C-0000025e] pbx.c:     -- Executing [s@macro-user-callerid:4] ExecIf("SIP/602-00000445", "1?Set(REALCALLERIDNUM=602)") in new stack
[2017-10-25 08:21:39] VERBOSE[6614][C-0000025e] pbx.c:     -- Executing [s@macro-user-callerid:5] Set("SIP/602-00000445", "AMPUSER=602") in new stack
[2017-10-25 08:21:39] VERBOSE[6614][C-0000025e] pbx.c:     -- Executing [s@macro-user-callerid:6] GotoIf("SIP/602-00000445", "0?limit") in new stack
[2017-10-25 08:21:39] VERBOSE[6614][C-0000025e] pbx.c:     -- Executing [s@macro-user-callerid:7] Set("SIP/602-00000445", "AMPUSERCIDNAME=Martijn") in new stack
[2017-10-25 08:21:39] VERBOSE[6614][C-0000025e] pbx.c:     -- Executing [s@macro-user-callerid:8] GotoIf("SIP/602-00000445", "0?report") in new stack
[2017-10-25 08:21:39] VERBOSE[6614][C-0000025e] pbx.c:     -- Executing [s@macro-user-callerid:9] Set("SIP/602-00000445", "AMPUSERCID=602") in new stack
[2017-10-25 08:21:39] VERBOSE[6614][C-0000025e] pbx.c:     -- Executing [s@macro-user-callerid:10] Set("SIP/602-00000445", "__DIAL_OPTIONS=Ttr") in new stack
[2017-10-25 08:21:39] VERBOSE[6614][C-0000025e] pbx.c:     -- Executing [s@macro-user-callerid:11] Set("SIP/602-00000445", "CALLERID(all)="Martijn" <602>") in new stack
[2017-10-25 08:21:39] VERBOSE[6614][C-0000025e] pbx.c:     -- Executing [s@macro-user-callerid:12] GotoIf("SIP/602-00000445", "0?limit") in new stack
[2017-10-25 08:21:39] VERBOSE[6614][C-0000025e] pbx.c:     -- Executing [s@macro-user-callerid:13] ExecIf("SIP/602-00000445", "0?Set(GROUP(concurrency_limit)=602)") in new stack
[2017-10-25 08:21:39] VERBOSE[6614][C-0000025e] pbx.c:     -- Executing [s@macro-user-callerid:14] ExecIf("SIP/602-00000445", "0?Set(CHANNEL(language)=)") in new stack
[2017-10-25 08:21:39] VERBOSE[6614][C-0000025e] pbx.c:     -- Executing [s@macro-user-callerid:15] GotoIf("SIP/602-00000445", "0?continue") in new stack
[2017-10-25 08:21:39] VERBOSE[6614][C-0000025e] pbx.c:     -- Executing [s@macro-user-callerid:16] ExecIf("SIP/602-00000445", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
[2017-10-25 08:21:39] VERBOSE[6614][C-0000025e] pbx.c:     -- Executing [s@macro-user-callerid:17] Set("SIP/602-00000445", "__TTL=64") in new stack
[2017-10-25 08:21:39] VERBOSE[6614][C-0000025e] pbx.c:     -- Executing [s@macro-user-callerid:18] GotoIf("SIP/602-00000445", "1?continue") in new stack
[2017-10-25 08:21:39] VERBOSE[6614][C-0000025e] pbx.c:     -- Goto (macro-user-callerid,s,29)
[2017-10-25 08:21:39] VERBOSE[6614][C-0000025e] pbx.c:     -- Executing [s@macro-user-callerid:29] Set("SIP/602-00000445", "CALLERID(number)=602") in new stack
[2017-10-25 08:21:39] VERBOSE[6614][C-0000025e] pbx.c:     -- Executing [s@macro-user-callerid:30] Set("SIP/602-00000445", "CALLERID(name)=Martijn") in new stack
[2017-10-25 08:21:39] VERBOSE[6614][C-0000025e] pbx.c:     -- Executing [s@macro-user-callerid:31] Set("SIP/602-00000445", "CDR(cnum)=602") in new stack
[2017-10-25 08:21:39] VERBOSE[6614][C-0000025e] pbx.c:     -- Executing [s@macro-user-callerid:32] Set("SIP/602-00000445", "CDR(cnam)=Martijn") in new stack
[2017-10-25 08:21:39] VERBOSE[6614][C-0000025e] pbx.c:     -- Executing [s@macro-user-callerid:33] Set("SIP/602-00000445", "CHANNEL(language)=en") in new stack
[2017-10-25 08:21:39] VERBOSE[6614][C-0000025e] pbx.c:     -- Executing [s@macro-user-callerid:34] GosubIf("SIP/602-00000445", "0?app-check-classofservce,s,1()") in new stack
[2017-10-25 08:21:39] VERBOSE[6614][C-0000025e] pbx.c:     -- Executing [711@from-internal:3] Goto("SIP/602-00000445", "ext-group,694,1") in new stack
[2017-10-25 08:21:39] VERBOSE[6614][C-0000025e] pbx.c:     -- Goto (ext-group,694,1)
[2017-10-25 08:21:39] VERBOSE[6614][C-0000025e] pbx.c:     -- Executing [694@ext-group:1] Set("SIP/602-00000445", "COS_DEST=694") in new stack
[2017-10-25 08:21:39] VERBOSE[6614][C-0000025e] pbx.c:     -- Executing [694@ext-group:2] Set("SIP/602-00000445", "__ZULU_TYPE=") in new stack
[2017-10-25 08:21:39] VERBOSE[6614][C-0000025e] pbx.c:     -- Executing [694@ext-group:3] Set("SIP/602-00000445", "__ZULU_URL=") in new stack
[2017-10-25 08:21:39] VERBOSE[6614][C-0000025e] pbx.c:     -- Executing [694@ext-group:4] Set("SIP/602-00000445", "COS_TYPE=RINGGROUP") in new stack
[2017-10-25 08:21:39] VERBOSE[6614][C-0000025e] pbx.c:     -- Executing [694@ext-group:5] GotoIf("SIP/602-00000445", "0?cid") in new stack
[2017-10-25 08:21:39] VERBOSE[6614][C-0000025e] pbx.c:     -- Executing [694@ext-group:6] PlayTones("SIP/602-00000445", "ring") in new stack
[2017-10-25 08:21:39] VERBOSE[6614][C-0000025e] pbx.c:     -- Executing [694@ext-group:7] Progress("SIP/602-00000445", "") in new stack
[2017-10-25 08:21:39] VERBOSE[6614][C-0000025e] pbx.c:     -- Executing [694@ext-group:8] Macro("SIP/602-00000445", "user-callerid,") in new stack
[2017-10-25 08:21:39] VERBOSE[6614][C-0000025e] pbx.c:     -- Executing [s@macro-user-callerid:1] Set("SIP/602-00000445", "TOUCH_MONITOR=1508912499.1227") in new stack
[2017-10-25 08:21:39] VERBOSE[6614][C-0000025e] pbx.c:     -- Executing [s@macro-user-callerid:2] Set("SIP/602-00000445", "AMPUSER=602") in new stack
[2017-10-25 08:21:39] VERBOSE[6614][C-0000025e] pbx.c:     -- Executing [s@macro-user-callerid:3] GotoIf("SIP/602-00000445", "7?report") in new stack
[2017-10-25 08:21:39] VERBOSE[6614][C-0000025e] pbx.c:     -- Goto (macro-user-callerid,s,15)
[2017-10-25 08:21:39] VERBOSE[6614][C-0000025e] pbx.c:     -- Executing [s@macro-user-callerid:15] GotoIf("SIP/602-00000445", "0?continue") in new stack
[2017-10-25 08:21:39] VERBOSE[6614][C-0000025e] pbx.c:     -- Executing [s@macro-user-callerid:16] ExecIf("SIP/602-00000445", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
[2017-10-25 08:21:39] VERBOSE[6614][C-0000025e] pbx.c:     -- Executing [s@macro-user-callerid:17] Set("SIP/602-00000445", "__TTL=63") in new stack
[2017-10-25 08:21:39] VERBOSE[6614][C-0000025e] pbx.c:     -- Executing [s@macro-user-callerid:18] GotoIf("SIP/602-00000445", "1?continue") in new stack
[2017-10-25 08:21:39] VERBOSE[6614][C-0000025e] pbx.c:     -- Goto (macro-user-callerid,s,29)
[2017-10-25 08:21:39] VERBOSE[6614][C-0000025e] pbx.c:     -- Executing [s@macro-user-callerid:29] Set("SIP/602-00000445", "CALLERID(number)=602") in new stack
[2017-10-25 08:21:39] VERBOSE[6614][C-0000025e] pbx.c:     -- Executing [s@macro-user-callerid:30] Set("SIP/602-00000445", "CALLERID(name)=Martijn") in new stack
[2017-10-25 08:21:39] VERBOSE[6614][C-0000025e] pbx.c:     -- Executing [s@macro-user-callerid:31] Set("SIP/602-00000445", "CDR(cnum)=602") in new stack
[2017-10-25 08:21:39] VERBOSE[6614][C-0000025e] pbx.c:     -- Executing [s@macro-user-callerid:32] Set("SIP/602-00000445", "CDR(cnam)=Martijn") in new stack
[2017-10-25 08:21:39] VERBOSE[6614][C-0000025e] pbx.c:     -- Executing [s@macro-user-callerid:33] Set("SIP/602-00000445", "CHANNEL(language)=en") in new stack
[2017-10-25 08:21:39] VERBOSE[6614][C-0000025e] pbx.c:     -- Executing [s@macro-user-callerid:34] GosubIf("SIP/602-00000445", "1?app-check-classofservce,s,1()") in new stack
[2017-10-25 08:21:39] VERBOSE[6614][C-0000025e] pbx.c:     -- Executing [s@app-check-classofservce:1] NoOp("SIP/602-00000445", "Starting COS Check") in new stack
[2017-10-25 08:21:39] VERBOSE[6614][C-0000025e] pbx.c:     -- Executing [s@app-check-classofservce:2] AGI("SIP/602-00000445", "cos.agi") in new stack
[2017-10-25 08:21:39] VERBOSE[6614][C-0000025e] res_agi.c:     -- Launched AGI Script /var/lib/asterisk/agi-bin/cos.agi
[2017-10-25 08:21:39] VERBOSE[6614][C-0000025e] res_agi.c:  cos.agi: Starting Class Of Service checks
[2017-10-25 08:21:46] NOTICE[4611] chan_sip.c: Peer '329' is now UNREACHABLE!  Last qualify: 14
[2017-10-25 08:21:48] VERBOSE[6614][C-0000025e] res_agi.c:     -- <SIP/602-00000445>AGI Script cos.agi completed, returning 4
[2017-10-25 08:21:48] VERBOSE[6614][C-0000025e] app_macro.c:   == Spawn extension (app-check-classofservce, s, 2) exited non-zero on 'SIP/602-00000445' in macro 'user-callerid'
[2017-10-25 08:21:48] VERBOSE[6614][C-0000025e] pbx.c:   == Spawn extension (app-check-classofservce, s, 2) exited non-zero on 'SIP/602-00000445'
[2017-10-25 08:22:12] NOTICE[4611] chan_sip.c: Peer '329' is now UNREACHABLE!  Last qualify: 2015

It’s failing at the Class of service check. Do you have COS licensed and configured? If so, it looks like you haven’t granted permission for this extension to dial the ring group. If not, uninstall the module.

Extension has permision, what is the quickist way to uninstall and install ?

The error shows it does not have permissions in COS to dial that

It has permission to dial. Otherwise it won’t work 80% off the time!