Incoming external call to ring group hangs up when should go to voicemail, internal call ok

Good evening,

I have a curious problem here that I’m hoping someone may be able to help me with.

The system is FreePBX 15.0.37.1 running in a VM. There are around 12 extensions and a few trunks, and several ring groups are set up.

The issue is that incoming external calls directed to a ring group ring the requisite extensions OK, and if they’re answered the call completes fine, but if there is no answer the call is hung up rather than being directed to the voicemail that’s set in the ring group for this case.

However incoming internal calls to that ring group complete as expected and the caller is directed to the voicemail (if not picked up).

If I set the inbound route to go direct to an extension instead of a ring group the calls are routed correctly to that extension’s voicemail when the phone isn’t picked up. This works for an extension (800) that’s within the ‘faulty’ ring group, so I’m fairly sure the extension setup itself isn’t the problem, and voicemail is clearly working.

To compound the issue everything was working fine prior to me shutting the server down in order to export an image of the VM for backup purposes before it going live on Monday. Upon restart all the usual functions appeared fine except for this unanticipated ring group issue. It’s almost as if the ring group is set to hang up upon no answer - but it definitely isn’t!

Below is a log excerpt showing what happens. The ring group number is 890, the two extensions in that group are 800 & 802. Upon no answer to 890 the call should be directed to 800’s voicemail.

If anyone could point me in the right direction I’d be grateful, thank you.


57381 pbx.c: Executing [SYSTEM_PH_NUMBER@from-trunk:1] Set(“SIP/Trunk1-0000004c”, “__DIRECTION=INBOUND”) in new stack
57382 pbx.c: Executing [SYSTEM_PH_NUMBER@from-trunk:2] Gosub(“SIP/Trunk1-0000004c”, “sub-record-check,s,1(in,SYSTEM_PH_NUMBER,dontcare)”) in new stack
57383 pbx.c: Executing [s@sub-record-check:1] GotoIf(“SIP/Trunk1-0000004c”, “0?initialized”) in new stack
57384 pbx.c: Executing [s@sub-record-check:2] Set(“SIP/Trunk1-0000004c”, “__REC_STATUS=INITIALIZED”) in new stack
57385 pbx.c: Executing [s@sub-record-check:3] Set(“SIP/Trunk1-0000004c”, “NOW=1690003756”) in new stack
57386 pbx.c: Executing [s@sub-record-check:4] Set(“SIP/Trunk1-0000004c”, “__DAY=22”) in new stack
57387 pbx.c: Executing [s@sub-record-check:5] Set(“SIP/Trunk1-0000004c”, “__MONTH=07”) in new stack
57388 pbx.c: Executing [s@sub-record-check:6] Set(“SIP/Trunk1-0000004c”, “__YEAR=2023”) in new stack
57389 pbx.c: Executing [s@sub-record-check:7] Set(“SIP/Trunk1-0000004c”, “__TIMESTR=20230722-172916”) in new stack
57390 pbx.c: Executing [s@sub-record-check:8] Set(“SIP/Trunk1-0000004c”, “__FROMEXTEN=unknown”) in new stack
57391 pbx.c: Executing [s@sub-record-check:9] Set(“SIP/Trunk1-0000004c”, “__MON_FMT=wav”) in new stack
57392 pbx.c: Executing [s@sub-record-check:10] NoOp(“SIP/Trunk1-0000004c”, “Recordings initialized”) in new stack
57393 pbx.c: Executing [s@sub-record-check:11] ExecIf(“SIP/Trunk1-0000004c”, “0?Set(ARG3=dontcare)”) in new stack
57394 pbx.c: Executing [s@sub-record-check:12] Set(“SIP/Trunk1-0000004c”, “REC_POLICY_MODE_SAVE=”) in new stack
57395 pbx.c: Executing [s@sub-record-check:13] ExecIf(“SIP/Trunk1-0000004c”, “0?Set(REC_STATUS=NO)”) in new stack
57396 pbx.c: Executing [s@sub-record-check:14] GotoIf(“SIP/Trunk1-0000004c”, “2?checkaction”) in new stack
57397 pbx_builtins.c: Goto (sub-record-check,s,17)
57398 pbx.c: Executing [s@sub-record-check:17] GotoIf(“SIP/Trunk1-0000004c”, “1?sub-record-check,in,1”) in new stack
57399 pbx_builtins.c: Goto (sub-record-check,in,1)
57400 pbx.c: Executing [in@sub-record-check:1] NoOp(“SIP/Trunk1-0000004c”, “Inbound Recording Check to SYSTEM_PH_NUMBER”) in new stack
57401 pbx.c: Executing [in@sub-record-check:2] Set(“SIP/Trunk1-0000004c”, “FROMEXTEN=unknown”) in new stack
57402 pbx.c: Executing [in@sub-record-check:3] ExecIf(“SIP/Trunk1-0000004c”, “11?Set(FROMEXTEN=CALLING_PH_NUMBER)”) in new stack
57403 pbx.c: Executing [in@sub-record-check:4] Gosub(“SIP/Trunk1-0000004c”, “recordcheck,1(dontcare,in,SYSTEM_PH_NUMBER)”) in new stack
57404 pbx.c: Executing [recordcheck@sub-record-check:1] NoOp(“SIP/Trunk1-0000004c”, “Starting recording check against dontcare”) in new stack
57405 pbx.c: Executing [recordcheck@sub-record-check:2] Goto(“SIP/Trunk1-0000004c”, “dontcare”) in new stack
57406 pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
57407 pbx.c: Executing [recordcheck@sub-record-check:3] Return(“SIP/Trunk1-0000004c”, “”) in new stack
57408 pbx.c: Executing [in@sub-record-check:5] Return(“SIP/Trunk1-0000004c”, “”) in new stack
57409 pbx.c: Executing [SYSTEM_PH_NUMBER@from-trunk:3] Set(“SIP/Trunk1-0000004c”, “CHANNEL(tonezone)=nz”) in new stack
57410 pbx.c: Executing [SYSTEM_PH_NUMBER@from-trunk:4] Set(“SIP/Trunk1-0000004c”, “__FROM_DID=SYSTEM_PH_NUMBER”) in new stack
57411 pbx.c: Executing [SYSTEM_PH_NUMBER@from-trunk:5] Set(“SIP/Trunk1-0000004c”, “returnhere=1”) in new stack
57412 pbx.c: Executing [SYSTEM_PH_NUMBER@from-trunk:6] Gosub(“SIP/Trunk1-0000004c”, “app-blacklist-check,s,1()”) in new stack
57413 pbx.c: Executing [s@app-blacklist-check:1] GotoIf(“SIP/Trunk1-0000004c”, “0?blacklisted”) in new stack
57414 pbx.c: Executing [s@app-blacklist-check:2] Set(“SIP/Trunk1-0000004c”, “CALLED_BLACKLIST=1”) in new stack
57415 pbx.c: Executing [s@app-blacklist-check:3] Return(“SIP/Trunk1-0000004c”, “”) in new stack
57416 pbx.c: Executing [SYSTEM_PH_NUMBER@from-trunk:7] Set(“SIP/Trunk1-0000004c”, “CDR(did)=SYSTEM_PH_NUMBER”) in new stack
57417 pbx.c: Executing [SYSTEM_PH_NUMBER@from-trunk:8] GotoIf(“SIP/Trunk1-0000004c”, “0?”) in new stack
57418 pbx.c: Executing [SYSTEM_PH_NUMBER@from-trunk:9] ExecIf(“SIP/Trunk1-0000004c”, “0 ?Set(CALLERID(name)=CALLING_PH_NUMBER)”) in new stack
57419 pbx.c: Executing [SYSTEM_PH_NUMBER@from-trunk:10] Set(“SIP/Trunk1-0000004c”, “__MOHCLASS=”) in new stack
57420 pbx.c: Executing [SYSTEM_PH_NUMBER@from-trunk:11] Set(“SIP/Trunk1-0000004c”, “__REVERSAL_REJECT=FALSE”) in new stack
57421 pbx.c: Executing [SYSTEM_PH_NUMBER@from-trunk:12] GotoIf(“SIP/Trunk1-0000004c”, “1?post-reverse-charge”) in new stack
57422 pbx_builtins.c: Goto (from-trunk,SYSTEM_PH_NUMBER,14)
57423 pbx.c: Executing [SYSTEM_PH_NUMBER@from-trunk:14] NoOp(“SIP/Trunk1-0000004c”, “”) in new stack
57424 pbx.c: Executing [SYSTEM_PH_NUMBER@from-trunk:15] Set(“SIP/Trunk1-0000004c”, “__CALLINGNAMEPRES_SV=allowed_not_screened”) in new stack
57425 pbx.c: Executing [SYSTEM_PH_NUMBER@from-trunk:16] Set(“SIP/Trunk1-0000004c”, “__CALLINGNUMPRES_SV=allowed_not_screened”) in new stack
57426 pbx.c: Executing [SYSTEM_PH_NUMBER@from-trunk:17] Set(“SIP/Trunk1-0000004c”, “CALLERID(name-pres)=allowed_not_screened”) in new stack
57427 pbx.c: Executing [SYSTEM_PH_NUMBER@from-trunk:18] Set(“SIP/Trunk1-0000004c”, “CALLERID(num-pres)=allowed_not_screened”) in new stack
57428 pbx.c: Executing [SYSTEM_PH_NUMBER@from-trunk:19] NoOp(“SIP/Trunk1-0000004c”, “CallerID Entry Point”) in new stack
57429 pbx.c: Executing [SYSTEM_PH_NUMBER@from-trunk:20] Set(“SIP/Trunk1-0000004c”, “__CRM_DIRECTION=INBOUND”) in new stack
57430 pbx.c: Executing [SYSTEM_PH_NUMBER@from-trunk:21] Set(“SIP/Trunk1-0000004c”, “__CRM_SOURCE=CALLING_PH_NUMBER”) in new stack
57431 pbx.c: Executing [SYSTEM_PH_NUMBER@from-trunk:22] Set(“SIP/Trunk1-0000004c”, “__CRM_LINKEDID=1690003756.84”) in new stack
57432 pbx.c: Executing [SYSTEM_PH_NUMBER@from-trunk:23] AGI(“SIP/Trunk1-0000004c”, “agi://127.0.0.1/sangomacrm.agi,true”) in new stack
57433 res_agi.c: <SIP/Trunk1-0000004c>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0
57434 pbx.c: Executing [SYSTEM_PH_NUMBER@from-trunk:24] ExecIf(“SIP/Trunk1-0000004c”, “1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)”) in new stack
57435 pbx.c: Executing [SYSTEM_PH_NUMBER@from-trunk:25] Goto(“SIP/Trunk1-0000004c”, “ext-group,890,1”) in new stack
57436 pbx_builtins.c: Goto (ext-group,890,1)
57437 pbx.c: Executing [890@ext-group:1] GotoIf(“SIP/Trunk1-0000004c”, “0?cid”) in new stack
57438 pbx.c: Executing [890@ext-group:2] PlayTones(“SIP/Trunk1-0000004c”, “ring”) in new stack
57439 pbx.c: Executing [890@ext-group:3] Progress(“SIP/Trunk1-0000004c”, “”) in new stack
57440 pbx.c: Executing [890@ext-group:4] Macro(“SIP/Trunk1-0000004c”, “user-callerid,”) in new stack
57441 pbx.c: Executing [s@macro-user-callerid:1] Set(“SIP/Trunk1-0000004c”, “TOUCH_MONITOR=1690003756.84”) in new stack
57442 pbx.c: Executing [s@macro-user-callerid:2] Set(“SIP/Trunk1-0000004c”, “CHANCONTEXT=”) in new stack
57443 pbx.c: Executing [s@macro-user-callerid:3] Set(“SIP/Trunk1-0000004c”, “CHANCONTEXT=”) in new stack
57444 pbx.c: Executing [s@macro-user-callerid:4] Set(“SIP/Trunk1-0000004c”, “CHANEXTENCONTEXT=Trunk1-0000004c”) in new stack
57445 pbx.c: Executing [s@macro-user-callerid:5] Set(“SIP/Trunk1-0000004c”, “CHANEXTEN=Trunk1-0000004c”) in new stack
57446 pbx.c: Executing [s@macro-user-callerid:6] Set(“SIP/Trunk1-0000004c”, “CALLERID(number)=CALLING_PH_NUMBER”) in new stack
57447 pbx.c: Executing [s@macro-user-callerid:7] Set(“SIP/Trunk1-0000004c”, “AMPUSER=CALLING_PH_NUMBER”) in new stack
57448 pbx.c: Executing [s@macro-user-callerid:8] Set(“SIP/Trunk1-0000004c”, “HOTDESCKCHAN=Trunk1-0000004c”) in new stack
57449 pbx.c: Executing [s@macro-user-callerid:9] Set(“SIP/Trunk1-0000004c”, “HOTDESKEXTEN=Trunk1”) in new stack
57450 pbx.c: Executing [s@macro-user-callerid:10] Set(“SIP/Trunk1-0000004c”, “HOTDESKCALL=0”) in new stack
57451 pbx.c: Executing [s@macro-user-callerid:11] ExecIf(“SIP/Trunk1-0000004c”, “0?Set(HOTDESKCALL=1)”) in new stack
57452 pbx.c: Executing [s@macro-user-callerid:12] ExecIf(“SIP/Trunk1-0000004c”, “0?Set(CALLERID(name)=)”) in new stack
57453 pbx.c: Executing [s@macro-user-callerid:13] GotoIf(“SIP/Trunk1-0000004c”, “0?report”) in new stack
57454 pbx.c: Executing [s@macro-user-callerid:14] ExecIf(“SIP/Trunk1-0000004c”, “1?Set(REALCALLERIDNUM=CALLING_PH_NUMBER)”) in new stack
57455 pbx.c: Executing [s@macro-user-callerid:15] Set(“SIP/Trunk1-0000004c”, “AMPUSER=”) in new stack
57456 pbx.c: Executing [s@macro-user-callerid:16] GotoIf(“SIP/Trunk1-0000004c”, “0?limit”) in new stack
57457 pbx.c: Executing [s@macro-user-callerid:17] Set(“SIP/Trunk1-0000004c”, “AMPUSERCIDNAME=”) in new stack
57458 pbx.c: Executing [s@macro-user-callerid:18] ExecIf(“SIP/Trunk1-0000004c”, “0?Set(__CIDMASQUERADING=TRUE)”) in new stack
57459 pbx.c: Executing [s@macro-user-callerid:19] GotoIf(“SIP/Trunk1-0000004c”, “1?report”) in new stack
57460 pbx_builtins.c: Goto (macro-user-callerid,s,28)
57461 pbx.c: Executing [s@macro-user-callerid:28] NoOp(“SIP/Trunk1-0000004c”, “Macro Depth is 1”) in new stack
57462 pbx.c: Executing [s@macro-user-callerid:29] GotoIf(“SIP/Trunk1-0000004c”, “1?report2:macroerror”) in new stack
57463 pbx_builtins.c: Goto (macro-user-callerid,s,30)
57464 pbx.c: Executing [s@macro-user-callerid:30] GotoIf(“SIP/Trunk1-0000004c”, “0?continue”) in new stack
57465 pbx.c: Executing [s@macro-user-callerid:31] ExecIf(“SIP/Trunk1-0000004c”, “1?Set(__CALLEE_ACCOUNCODE=)”) in new stack
57466 pbx.c: Executing [s@macro-user-callerid:32] Set(“SIP/Trunk1-0000004c”, “TTL=64") in new stack
57467 pbx.c: Executing [s@macro-user-callerid:33] GotoIf(“SIP/Trunk1-0000004c”, “1?continue”) in new stack
57468 pbx_builtins.c: Goto (macro-user-callerid,s,49)
57469 pbx.c: Executing [s@macro-user-callerid:49] Set(“SIP/Trunk1-0000004c”, “CALLERID(number)=CALLING_PH_NUMBER”) in new stack
57470 pbx.c: Executing [s@macro-user-callerid:50] Set(“SIP/Trunk1-0000004c”, "CALLERID(name)=Luke P
") in new stack
57471 pbx.c: Executing [s@macro-user-callerid:51] GotoIf(“SIP/Trunk1-0000004c”, “0?cnum”) in new stack
57472 pbx.c: Executing [s@macro-user-callerid:52] Set(“SIP/Trunk1-0000004c”, "CDR(cnam)=Luke P
”) in new stack
57473 pbx.c: Executing [s@macro-user-callerid:53] Set(“SIP/Trunk1-0000004c”, “CDR(cnum)=CALLING_PH_NUMBER”) in new stack
57474 pbx.c: Executing [s@macro-user-callerid:54] Set(“SIP/Trunk1-0000004c”, “CHANNEL(language)=en_NZ”) in new stack
57475 pbx.c: Executing [890@ext-group:5] Macro(“SIP/Trunk1-0000004c”, “blkvm-setifempty,”) in new stack
57476 pbx.c: Executing [s@macro-blkvm-setifempty:1] GotoIf(“SIP/Trunk1-0000004c”, “1?init”) in new stack
57477 pbx_builtins.c: Goto (macro-blkvm-setifempty,s,4)
57478 pbx.c: Executing [s@macro-blkvm-setifempty:4] Set(“SIP/Trunk1-0000004c”, “__BLKVM_CHANNEL=SIP/Trunk1-0000004c”) in new stack
57479 pbx.c: Executing [s@macro-blkvm-setifempty:5] Set(“SIP/Trunk1-0000004c”, “SHARED(BLKVM,SIP/Trunk1-0000004c)=TRUE”) in new stack
57480 pbx.c: Executing [s@macro-blkvm-setifempty:6] Set(“SIP/Trunk1-0000004c”, “GOSUB_RETVAL=TRUE”) in new stack
57481 pbx.c: Executing [s@macro-blkvm-setifempty:7] MacroExit(“SIP/Trunk1-0000004c”, “”) in new stack
57482 pbx.c: Executing [890@ext-group:6] GotoIf(“SIP/Trunk1-0000004c”, “1?skipov”) in new stack
57483 pbx_builtins.c: Goto (ext-group,890,9)
57484 pbx.c: Executing [890@ext-group:9] Set(“SIP/Trunk1-0000004c”, “RRNODEST=”) in new stack
57485 pbx.c: Executing [890@ext-group:10] Set(“SIP/Trunk1-0000004c”, “__NODEST=890”) in new stack
57486 pbx.c: Executing [890@ext-group:11] GosubIf(“SIP/Trunk1-0000004c”, “0?sub-rgsetcid,s,1()”) in new stack
57487 pbx.c: Executing [890@ext-group:12] Gosub(“SIP/Trunk1-0000004c”, “sub-record-check,s,1(rg,890,dontcare)”) in new stack
57488 pbx.c: Executing [s@sub-record-check:1] GotoIf(“SIP/Trunk1-0000004c”, “11?initialized”) in new stack
57489 pbx_builtins.c: Goto (sub-record-check,s,10)
57490 pbx.c: Executing [s@sub-record-check:10] NoOp(“SIP/Trunk1-0000004c”, “Recordings initialized”) in new stack
57491 pbx.c: Executing [s@sub-record-check:11] ExecIf(“SIP/Trunk1-0000004c”, “0?Set(ARG3=dontcare)”) in new stack
57492 pbx.c: Executing [s@sub-record-check:12] Set(“SIP/Trunk1-0000004c”, “REC_POLICY_MODE_SAVE=”) in new stack
57493 pbx.c: Executing [s@sub-record-check:13] ExecIf(“SIP/Trunk1-0000004c”, “0?Set(REC_STATUS=NO)”) in new stack
57494 pbx.c: Executing [s@sub-record-check:14] GotoIf(“SIP/Trunk1-0000004c”, “2?checkaction”) in new stack
57495 pbx_builtins.c: Goto (sub-record-check,s,17)
57496 pbx.c: Executing [s@sub-record-check:17] GotoIf(“SIP/Trunk1-0000004c”, “0?sub-record-check,rg,1”) in new stack
57497 pbx.c: Executing [s@sub-record-check:18] NoOp(“SIP/Trunk1-0000004c”, “Generic rg Recording Check - CALLING_PH_NUMBER 890”) in new stack
57498 pbx.c: Executing [s@sub-record-check:19] Gosub(“SIP/Trunk1-0000004c”, “recordcheck,1(dontcare,rg,890)”) in new stack
57499 pbx.c: Executing [recordcheck@sub-record-check:1] NoOp(“SIP/Trunk1-0000004c”, “Starting recording check against dontcare”) in new stack
57500 pbx.c: Executing [recordcheck@sub-record-check:2] Goto(“SIP/Trunk1-0000004c”, “dontcare”) in new stack
57501 pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
57502 pbx.c: Executing [recordcheck@sub-record-check:3] Return(“SIP/Trunk1-0000004c”, “”) in new stack
57503 pbx.c: Executing [s@sub-record-check:20] Return(“SIP/Trunk1-0000004c”, “”) in new stack
57504 pbx.c: Executing [890@ext-group:13] Set(“SIP/Trunk1-0000004c”, “RingGroupMethod=ringall”) in new stack
57505 pbx.c: Executing [890@ext-group:14] Set(“SIP/Trunk1-0000004c”, “__ALT_CONFIRM_MSG=”) in new stack
57506 pbx.c: Executing [890@ext-group:15] GotoIf(“SIP/Trunk1-0000004c”, “0?RGVQANNOUNCE:NORGVQANNOUNCE”) in new stack
57507 pbx_builtins.c: Goto (ext-group,890,18)
57508 pbx.c: Executing [890@ext-group:18] Macro(“SIP/Trunk1-0000004c”, “dial,20,HhTtrQ(NO_ANSWER),800-802”) in new stack
57509 pbx.c: Executing [s@macro-dial:1] NoOp(“SIP/Trunk1-0000004c”, "Blind Transfer: , Attended Transfer: , User: , Alert Info: ") in new stack
57510 pbx.c: Executing [s@macro-dial:2] Set(“SIP/Trunk1-0000004c”, “__CRM_SOURCE=CALLING_PH_NUMBER”) in new stack
57511 pbx.c: Executing [s@macro-dial:3] ExecIf(“SIP/Trunk1-0000004c”, “0?Set(ALERT_INFO=)”) in new stack
57512 pbx.c: Executing [s@macro-dial:4] ExecIf(“SIP/Trunk1-0000004c”, “0?Set(ALERT_INFO=)”) in new stack
57513 pbx.c: Executing [s@macro-dial:5] ExecIf(“SIP/Trunk1-0000004c”, “0?Set(ALERT_INFO=)”) in new stack
57514 pbx.c: Executing [s@macro-dial:6] ExecIf(“SIP/Trunk1-0000004c”, “0?Set(CHANNEL(musicclass)=)”) in new stack
57515 pbx.c: Executing [s@macro-dial:7] AGI(“SIP/Trunk1-0000004c”, “agi://127.0.0.1/dialparties.agi”) in new stack
57516 res_agi.c: agi://127.0.0.1/dialparties.agi: Starting New Dialparties.agi
57517 res_agi.c: agi://127.0.0.1/dialparties.agi: Caller ID name is ‘JOE’ number is ‘CALLING_PH_NUMBER’
57518 res_agi.c: agi://127.0.0.1/dialparties.agi: CW Ignore is:
57519 res_agi.c: agi://127.0.0.1/dialparties.agi: CF Ignore is:
57520 res_agi.c: agi://127.0.0.1/dialparties.agi: CW IN_USE/BUSY is: 1
57521 res_agi.c: agi://127.0.0.1/dialparties.agi: Ringgroup confirm is :
57522 res_agi.c: agi://127.0.0.1/dialparties.agi: Methodology of ring is ‘ringall’
57523 res_agi.c: agi://127.0.0.1/dialparties.agi: Added extension 800 to extension map
57524 res_agi.c: agi://127.0.0.1/dialparties.agi: Added extension 802 to extension map
57525 res_agi.c: agi://127.0.0.1/dialparties.agi: Extension 800 cf is disabled
57526 res_agi.c: agi://127.0.0.1/dialparties.agi: Extension 802 cf is disabled
57527 res_agi.c: agi://127.0.0.1/dialparties.agi: Extension 800 do not disturb is disabled
57528 res_agi.c: agi://127.0.0.1/dialparties.agi: Extension 802 do not disturb is disabled
57529 res_agi.c: agi://127.0.0.1/dialparties.agi: dbset CALLTRACE/800 to CALLING_PH_NUMBER
57530 res_agi.c: agi://127.0.0.1/dialparties.agi: dbset CALLTRACE/802 to CALLING_PH_NUMBER
57531 res_agi.c: agi://127.0.0.1/dialparties.agi: Filtered ARG3: 800-802
57532 res_agi.c: agi://127.0.0.1/dialparties.agi: RVOL_MODE ‘’
57533 res_agi.c: agi://127.0.0.1/dialparties.agi: RVOL is:
57534 res_agi.c: agi://127.0.0.1/dialparties.agi: RVOLPARENT is:
57535 res_agi.c: <SIP/Trunk1-0000004c>AGI Script agi://127.0.0.1/dialparties.agi completed, returning 0
57536 pbx.c: Executing [s@macro-dial:8] GotoIf(“SIP/Trunk1-0000004c”, “1?normdial”) in new stack
57537 pbx_builtins.c: Goto (macro-dial,s,11)
57538 pbx.c: Executing [s@macro-dial:11] NoOp(“SIP/Trunk1-0000004c”, “Returned from dialparties with groups to dial”) in new stack
57539 pbx.c: Executing [s@macro-dial:12] NoOp(“SIP/Trunk1-0000004c”, "ringall array ") in new stack
57540 pbx.c: Executing [s@macro-dial:13] NoOp(“SIP/Trunk1-0000004c”, "ds= SIP/800&SIP/802,20,HhtrQ(NO_ANSWER)M(auto-blkvm) ") in new stack
57541 pbx.c: Executing [s@macro-dial:14] NoOp(“SIP/Trunk1-0000004c”, "dsextra= ") in new stack
57542 pbx.c: Executing [s@macro-dial:15] Set(“SIP/Trunk1-0000004c”, “ds=SIP/800&SIP/802,20,HhtrQ(NO_ANSWER)M(auto-blkvm)”) in new stack
57543 pbx.c: Executing [s@macro-dial:16] NoOp(“SIP/Trunk1-0000004c”, “ds= SIP/800&SIP/802,20,HhtrQ(NO_ANSWER)M(auto-blkvm)”) in new stack
57544 pbx.c: Executing [s@macro-dial:17] Set(“SIP/Trunk1-0000004c”, “__FMGL_DIAL=”) in new stack
57545 pbx.c: Executing [s@macro-dial:18] Set(“SIP/Trunk1-0000004c”, “LOOPCNT=2”) in new stack
57546 pbx.c: Executing [s@macro-dial:19] Set(“SIP/Trunk1-0000004c”, “ITER=1”) in new stack
57547 pbx.c: Executing [s@macro-dial:20] Set(“SIP/Trunk1-0000004c”, “__EXTTOCALL=800”) in new stack
57548 pbx.c: Executing [s@macro-dial:21] NoOp(“SIP/Trunk1-0000004c”, “Working with 800”) in new stack
57549 pbx.c: Executing [s@macro-dial:22] ExecIf(“SIP/Trunk1-0000004c”, “0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)”) in new stack
57550 pbx.c: Executing [s@macro-dial:23] ExecIf(“SIP/Trunk1-0000004c”, “0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)”) in new stack
57551 pbx.c: Executing [s@macro-dial:24] Set(“SIP/Trunk1-0000004c”, “ITER=2”) in new stack
57552 pbx.c: Executing [s@macro-dial:25] GotoIf(“SIP/Trunk1-0000004c”, “1?ndloopbegin”) in new stack
57553 pbx_builtins.c: Goto (macro-dial,s,20)
57554 pbx.c: Executing [s@macro-dial:20] Set(“SIP/Trunk1-0000004c”, “__EXTTOCALL=802”) in new stack
57555 pbx.c: Executing [s@macro-dial:21] NoOp(“SIP/Trunk1-0000004c”, “Working with 802”) in new stack
57556 pbx.c: Executing [s@macro-dial:22] ExecIf(“SIP/Trunk1-0000004c”, “0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)”) in new stack
57557 pbx.c: Executing [s@macro-dial:23] ExecIf(“SIP/Trunk1-0000004c”, “0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)”) in new stack
57558 pbx.c: Executing [s@macro-dial:24] Set(“SIP/Trunk1-0000004c”, “ITER=3”) in new stack
57559 pbx.c: Executing [s@macro-dial:25] GotoIf(“SIP/Trunk1-0000004c”, “0?ndloopbegin”) in new stack
57560 pbx.c: Executing [s@macro-dial:26] Macro(“SIP/Trunk1-0000004c”, “dial-ringall-predial-hook,”) in new stack
57561 pbx.c: Executing [s@macro-dial-ringall-predial-hook:1] MacroExit(“SIP/Trunk1-0000004c”, “”) in new stack
57562 pbx.c: Executing [s@macro-dial:27] ExecIf(“SIP/Trunk1-0000004c”, “0?Set(CWRING=r(callwaiting)):Set(CWRING=)”) in new stack
57563 pbx.c: Executing [s@macro-dial:28] ExecIf(“SIP/Trunk1-0000004c”, “0?Set(ds=SIP/800&SIP/802,20,HhtrQ(NO_ANSWER)M(auto-blkvm)g)”) in new stack
57564 pbx.c: Executing [s@macro-dial:29] Dial(“SIP/Trunk1-0000004c”, “SIP/800&SIP/802,20,HhtrQ(NO_ANSWER)M(auto-blkvm)b(func-apply-sipheaders^s^1),”) in new stack
57565 netsock2.c: Using SIP RTP TOS bits 184
57566 netsock2.c: Using SIP RTP CoS mark 5
57567 netsock2.c: Using SIP RTP TOS bits 184
57568 netsock2.c: Using SIP RTP CoS mark 5
57569 app_stack.c: SIP/800-0000004d Internal Gosub(func-apply-sipheaders,s,1) start
57570 pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf(“SIP/800-0000004d”, “1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)”) in new stack
57571 pbx.c: Executing [s@func-apply-sipheaders:2] NoOp(“SIP/800-0000004d”, “Applying SIP Headers to channel SIP/800-0000004d”) in new stack
57572 pbx.c: Executing [s@func-apply-sipheaders:3] Set(“SIP/800-0000004d”, “TECH=SIP”) in new stack
57573 pbx.c: Executing [s@func-apply-sipheaders:4] Set(“SIP/800-0000004d”, “SIPHEADERKEYS=”) in new stack
57574 pbx.c: Executing [s@func-apply-sipheaders:5] While(“SIP/800-0000004d”, “0”) in new stack
57575 app_while.c: Jumping to priority 13
57576 pbx.c: Executing [s@func-apply-sipheaders:14] Return(“SIP/800-0000004d”, “”) in new stack
57577 app_stack.c: Spawn extension (from-internal, 890, 1) exited non-zero on ‘SIP/800-0000004d’
57578 app_stack.c: SIP/800-0000004d Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
57579 app_stack.c: SIP/802-0000004e Internal Gosub(func-apply-sipheaders,s,1) start
57580 pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf(“SIP/802-0000004e”, “1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)”) in new stack
57581 pbx.c: Executing [s@func-apply-sipheaders:2] NoOp(“SIP/802-0000004e”, “Applying SIP Headers to channel SIP/802-0000004e”) in new stack
57582 pbx.c: Executing [s@func-apply-sipheaders:3] Set(“SIP/802-0000004e”, “TECH=SIP”) in new stack
57583 pbx.c: Executing [s@func-apply-sipheaders:4] Set(“SIP/802-0000004e”, “SIPHEADERKEYS=”) in new stack
57584 pbx.c: Executing [s@func-apply-sipheaders:5] While(“SIP/802-0000004e”, “0”) in new stack
57585 app_while.c: Jumping to priority 13
57586 pbx.c: Executing [s@func-apply-sipheaders:14] Return(“SIP/802-0000004e”, “”) in new stack
57587 app_stack.c: Spawn extension (from-internal, 890, 1) exited non-zero on ‘SIP/802-0000004e’
57588 app_stack.c: SIP/802-0000004e Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
57589 app_dial.c: Called SIP/800
57590 app_dial.c: Called SIP/802
57591 app_dial.c: SIP/802-0000004e connected line has changed. Saving it until answer for SIP/Trunk1-0000004c
57592 app_dial.c: SIP/800-0000004d connected line has changed. Saving it until answer for SIP/Trunk1-0000004c
57593[2023-07-22 17:29:16] VERBOSE[4168] chan_sip.c: Extension Changed 800[ext-local] new state Ringing for Notify User 802
57594[2023-07-22 17:29:16] VERBOSE[4168] chan_sip.c: Extension Changed 802[ext-local] new state Ringing for Notify User 800
57595[2023-07-22 17:29:16] VERBOSE[4168] chan_sip.c: Extension Changed 802[ext-local] new state Ringing for Notify User 800
57596 app_dial.c: SIP/802-0000004e is ringing
57597[2023-07-22 17:29:16] VERBOSE[4168] chan_sip.c: Extension Changed 800[ext-local] new state Ringing for Notify User 802
57598 app_dial.c: SIP/800-0000004d is ringing
57599 app_stack.c: SIP/800-0000004d Internal Gosub(crm-hangup,s,1) start
57600 pbx.c: Executing [s@crm-hangup:1] NoOp(“SIP/800-0000004d”, “Sending Hangup to CRM”) in new stack
57601 pbx.c: Executing [s@crm-hangup:2] NoOp(“SIP/800-0000004d”, “HANGUP CAUSE: 16”) in new stack
57602 pbx.c: Executing [s@crm-hangup:3] ExecIf(“SIP/800-0000004d”, “0?Set(__CRM_VOICEMAIL=)”) in new stack
57603 pbx.c: Executing [s@crm-hangup:4] NoOp(“SIP/800-0000004d”, “MASTER CHANNEL: 1690003756.85 = 1690003756.84”) in new stack
57604 pbx.c: Executing [s@crm-hangup:5] GotoIf(“SIP/800-0000004d”, “1?return”) in new stack
57605 pbx_builtins.c: Goto (crm-hangup,s,8)
57606 pbx.c: Executing [s@crm-hangup:8] Return(“SIP/800-0000004d”, “”) in new stack
57607 app_stack.c: Spawn extension (from-internal, 890, 1) exited non-zero on ‘SIP/800-0000004d’
57608 app_stack.c: SIP/800-0000004d Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
57609 app_stack.c: SIP/802-0000004e Internal Gosub(crm-hangup,s,1) start
57610 pbx.c: Executing [s@crm-hangup:1] NoOp(“SIP/802-0000004e”, “Sending Hangup to CRM”) in new stack
57611 pbx.c: Executing [s@crm-hangup:2] NoOp(“SIP/802-0000004e”, “HANGUP CAUSE: 16”) in new stack
57612 pbx.c: Executing [s@crm-hangup:3] ExecIf(“SIP/802-0000004e”, “0?Set(__CRM_VOICEMAIL=)”) in new stack
57613 pbx.c: Executing [s@crm-hangup:4] NoOp(“SIP/802-0000004e”, “MASTER CHANNEL: 1690003756.86 = 1690003756.84”) in new stack
57614 pbx.c: Executing [s@crm-hangup:5] GotoIf(“SIP/802-0000004e”, “1?return”) in new stack
57615 pbx_builtins.c: Goto (crm-hangup,s,8)
57616 pbx.c: Executing [s@crm-hangup:8] Return(“SIP/802-0000004e”, “”) in new stack
57617 app_stack.c: Spawn extension (from-internal, 890, 1) exited non-zero on ‘SIP/802-0000004e’
57618 app_stack.c: SIP/802-0000004e Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
57619 app_macro.c: Spawn extension (macro-dial, s, 29) exited non-zero on ‘SIP/Trunk1-0000004c’ in macro ‘dial’
57620 pbx.c: Spawn extension (ext-group, 890, 18) exited non-zero on ‘SIP/Trunk1-0000004c’
57621 pbx.c: Executing [h@ext-group:1] Macro(“SIP/Trunk1-0000004c”, “hangupcall,”) in new stack
57622 pbx.c: Executing [s@macro-hangupcall:1] GotoIf(“SIP/Trunk1-0000004c”, “1?theend”) in new stack
57623 pbx_builtins.c: Goto (macro-hangupcall,s,3)
57624[2023-07-22 17:29:36] VERBOSE[4168] chan_sip.c: Extension Changed 800[ext-local] new state Idle for Notify User 802
57625[2023-07-22 17:29:36] VERBOSE[4168] chan_sip.c: Extension Changed 802[ext-local] new state Idle for Notify User 800
57626 pbx.c: Executing [s@macro-hangupcall:3] ExecIf(“SIP/Trunk1-0000004c”, “0?Set(CDR(recordingfile)=)”) in new stack
57627 pbx.c: Executing [s@macro-hangupcall:4] Hangup(“SIP/Trunk1-0000004c”, “”) in new stack
57628 app_macro.c: Spawn extension (macro-hangupcall, s, 4) exited non-zero on ‘SIP/Trunk1-0000004c’ in macro ‘hangupcall’
57629 pbx.c: Spawn extension (ext-group, h, 1) exited non-zero on ‘SIP/Trunk1-0000004c’
57630 app_stack.c: SIP/Trunk1-0000004c Internal Gosub(crm-hangup,s,1) start
57631 pbx.c: Executing [s@crm-hangup:1] NoOp(“SIP/Trunk1-0000004c”, “Sending Hangup to CRM”) in new stack
57632 pbx.c: Executing [s@crm-hangup:2] NoOp(“SIP/Trunk1-0000004c”, “HANGUP CAUSE: 16”) in new stack
57633 pbx.c: Executing [s@crm-hangup:3] ExecIf(“SIP/Trunk1-0000004c”, “0?Set(__CRM_VOICEMAIL=)”) in new stack
57634 pbx.c: Executing [s@crm-hangup:4] NoOp(“SIP/Trunk1-0000004c”, “MASTER CHANNEL: 1690003756.84 = 1690003756.84”) in new stack
57635 pbx.c: Executing [s@crm-hangup:5] GotoIf(“SIP/Trunk1-0000004c”, “0?return”) in new stack
57636 pbx.c: Executing [s@crm-hangup:6] Set(“SIP/Trunk1-0000004c”, “__CRM_HANGUP=1”) in new stack
57637 pbx.c: Executing [s@crm-hangup:7] AGI(“SIP/Trunk1-0000004c”, “agi://127.0.0.1/sangomacrm.agi”) in new stack
57638 res_agi.c: <SIP/Trunk1-0000004c>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0
57639 pbx.c: Executing [s@crm-hangup:8] Return(“SIP/Trunk1-0000004c”, “”) in new stack
57640 app_stack.c: Spawn extension (ext-group, h, 1) exited non-zero on ‘SIP/Trunk1-0000004c’
57641 app_stack.c: SIP/Trunk1-0000004c Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=

As is so often the way I found a solution almost immediately upon posting here!

It turns out that if I set the ring group ring time to 19 sec (down from 20 sec) the system operates as it should and both internal and external calls to that ring group terminate at the requisite voicemail in the event of no answer. Return to 20 sec and the fault reappears.

I don’t know why this fixes the issue, perhaps it’s due to both the call group redirect and a member extension voicemail clash in attempting to operate at the same time with the result that the system simply hangs up? FWIW there have been no changes to the default extension/voicemail timer settings.

Advice would be useful as if this is correct it may be a case that could perhaps be usefully flagged by the software, in which case I should raise a ticket?

Otherwise I could be completely wrong, but either way I guess the answer may be useful to someone in the future…

This topic was automatically closed 30 days after the last reply. New replies are no longer allowed.