Call Back Queue

I am trying to get queuecallback to work. While I can get the prompt to play and the customer to confirm, when the call goes back to call the customer, it instantly fails with “Call Declined by the User”. I have checked by dialtone provider, it shows no call attempted. Looking in the log, as soon as I push 1 to confirm, the call seems to attempt, but fail.

except from log:

[2023-01-17 20:34:01] VERBOSE[15974][C-0000000d] res_musiconhold.c: Stopped music on hold on PJSIP/Telnyx_-_Durango-0000001a
[2023-01-17 20:34:01] VERBOSE[15974][C-0000000d] app_queue.c: Playing periodic announcement
[2023-01-17 20:34:01] VERBOSE[15974][C-0000000d] file.c: <PJSIP/Telnyx_-_Durango-0000001a> Playing 'custom/callback-holdplace-v1.slin' (language 'en')
[2023-01-17 20:34:01] VERBOSE[465] asterisk.c: Remote UNIX connection
[2023-01-17 20:34:01] VERBOSE[16053] asterisk.c: Remote UNIX connection disconnected
[2023-01-17 20:34:07] VERBOSE[15974][C-0000000d] pbx.c: Executing [1@queuecallback-1:1] Set("PJSIP/Telnyx_-_Durango-0000001a", "ITER=0") in new stack
[2023-01-17 20:34:07] VERBOSE[15974][C-0000000d] pbx.c: Executing [1@queuecallback-1:2] Goto("PJSIP/Telnyx_-_Durango-0000001a", "request,1") in new stack
[2023-01-17 20:34:07] VERBOSE[15974][C-0000000d] pbx_builtins.c: Goto (queuecallback-1,request,1)
[2023-01-17 20:34:07] VERBOSE[15974][C-0000000d] pbx.c: Executing [request@queuecallback-1:1] Set("PJSIP/Telnyx_-_Durango-0000001a", "CALLBACKNUM=2812209483") in new stack
[2023-01-17 20:34:07] VERBOSE[15974][C-0000000d] pbx.c: Executing [request@queuecallback-1:2] GotoIf("PJSIP/Telnyx_-_Durango-0000001a", "0?enternum") in new stack
[2023-01-17 20:34:07] VERBOSE[15974][C-0000000d] pbx.c: Executing [request@queuecallback-1:3] SayDigits("PJSIP/Telnyx_-_Durango-0000001a", "2812209483") in new stack
[2023-01-17 20:34:07] VERBOSE[15974][C-0000000d] file.c: <PJSIP/Telnyx_-_Durango-0000001a> Playing 'digits/2.ulaw' (language 'en')
[2023-01-17 20:34:08] VERBOSE[15974][C-0000000d] file.c: <PJSIP/Telnyx_-_Durango-0000001a> Playing 'digits/8.ulaw' (language 'en')
[2023-01-17 20:34:09] VERBOSE[15974][C-0000000d] file.c: <PJSIP/Telnyx_-_Durango-0000001a> Playing 'digits/1.ulaw' (language 'en')
[2023-01-17 20:34:10] VERBOSE[15974][C-0000000d] file.c: <PJSIP/Telnyx_-_Durango-0000001a> Playing 'digits/2.ulaw' (language 'en')
[2023-01-17 20:34:10] VERBOSE[15974][C-0000000d] file.c: <PJSIP/Telnyx_-_Durango-0000001a> Playing 'digits/2.ulaw' (language 'en')
[2023-01-17 20:34:11] VERBOSE[15974][C-0000000d] file.c: <PJSIP/Telnyx_-_Durango-0000001a> Playing 'digits/0.ulaw' (language 'en')
[2023-01-17 20:34:11] VERBOSE[465] asterisk.c: Remote UNIX connection
[2023-01-17 20:34:11] VERBOSE[16131] asterisk.c: Remote UNIX connection disconnected
[2023-01-17 20:34:12] VERBOSE[15974][C-0000000d] file.c: <PJSIP/Telnyx_-_Durango-0000001a> Playing 'digits/9.ulaw' (language 'en')
[2023-01-17 20:34:13] VERBOSE[15974][C-0000000d] file.c: <PJSIP/Telnyx_-_Durango-0000001a> Playing 'digits/4.ulaw' (language 'en')
[2023-01-17 20:34:14] VERBOSE[15974][C-0000000d] file.c: <PJSIP/Telnyx_-_Durango-0000001a> Playing 'digits/8.ulaw' (language 'en')
[2023-01-17 20:34:14] VERBOSE[15974][C-0000000d] file.c: <PJSIP/Telnyx_-_Durango-0000001a> Playing 'digits/3.ulaw' (language 'en')
[2023-01-17 20:34:15] VERBOSE[15974][C-0000000d] pbx.c: Executing [request@queuecallback-1:4] Read("PJSIP/Telnyx_-_Durango-0000001a", "USECALLERID,vqplus-enter-number,1,,,") in new stack
[2023-01-17 20:34:15] VERBOSE[15974][C-0000000d] app_read.c: Accepting a maximum of 1 digits.
[2023-01-17 20:34:15] VERBOSE[15974][C-0000000d] file.c: <PJSIP/Telnyx_-_Durango-0000001a> Playing 'vqplus-enter-number.slin' (language 'en')
[2023-01-17 20:34:18] VERBOSE[15974][C-0000000d] app_read.c: User entered '1'
[2023-01-17 20:34:18] VERBOSE[15974][C-0000000d] pbx.c: Executing [request@queuecallback-1:5] GotoIf("PJSIP/Telnyx_-_Durango-0000001a", "0?enternum") in new stack
[2023-01-17 20:34:18] VERBOSE[15974][C-0000000d] pbx.c: Executing [request@queuecallback-1:6] Set("PJSIP/Telnyx_-_Durango-0000001a", "CALLBACKNUM=2812209483") in new stack
[2023-01-17 20:34:18] VERBOSE[15974][C-0000000d] pbx.c: Executing [request@queuecallback-1:7] Goto("PJSIP/Telnyx_-_Durango-0000001a", "callback") in new stack
[2023-01-17 20:34:18] VERBOSE[15974][C-0000000d] pbx_builtins.c: Goto (queuecallback-1,request,19)
[2023-01-17 20:34:18] VERBOSE[15974][C-0000000d] pbx.c: Executing [request@queuecallback-1:19] UserEvent("PJSIP/Telnyx_-_Durango-0000001a", "QueueCallbackRequest,Queue: 5800,Position: 1,CallbackId: 1,CallbackNum: 2812209483,QUEUEJOINTIME: 1674009221") in new stack
[2023-01-17 20:34:18] VERBOSE[15974][C-0000000d] pbx.c: Executing [request@queuecallback-1:20] Playback("PJSIP/Telnyx_-_Durango-0000001a", "vqplus-confirmed") in new stack
[2023-01-17 20:34:18] VERBOSE[15974][C-0000000d] file.c: <PJSIP/Telnyx_-_Durango-0000001a> Playing 'vqplus-confirmed.slin' (language 'en')
[2023-01-17 20:34:18] VERBOSE[16154] dial.c: Called 2812209483@queuecallback-ret-1/n
[2023-01-17 20:34:18] VERBOSE[16155][C-0000000e] pbx.c: Executing [2812209483@queuecallback-ret-1:1] Set("Local/2812209483@queuecallback-ret-1-00000012;2", "__FORCE_CONFIRM=Local/2812209483@queuecallback-ret-1-00000012;2") in new stack
[2023-01-17 20:34:18] VERBOSE[16155][C-0000000e] pbx.c: Executing [2812209483@queuecallback-ret-1:2] Set("Local/2812209483@queuecallback-ret-1-00000012;2", "__QCALLBACK=1") in new stack
[2023-01-17 20:34:18] VERBOSE[16155][C-0000000e] pbx.c: Executing [2812209483@queuecallback-ret-1:3] UserEvent("Local/2812209483@queuecallback-ret-1-00000012;2", "QueueDialAttempts,QUEUEREQUESTID: 1674009210.60,CALLID:1674009258.66") in new stack
[2023-01-17 20:34:18] VERBOSE[16155][C-0000000e] pbx.c: Executing [2812209483@queuecallback-ret-1:4] Set("Local/2812209483@queuecallback-ret-1-00000012;2", "SHARED(ANSWER_STATUS,Local/2812209483@queuecallback-ret-1-00000012;2)=NOANSWER") in new stack
[2023-01-17 20:34:18] VERBOSE[16155][C-0000000e] pbx.c: Executing [2812209483@queuecallback-ret-1:5] Set("Local/2812209483@queuecallback-ret-1-00000012;2", "__ALT_CONFIRM_MSG=custom/callback-queue-dialout-v1") in new stack
[2023-01-17 20:34:18] VERBOSE[16155][C-0000000e] pbx.c: Executing [2812209483@queuecallback-ret-1:6] Dial("Local/2812209483@queuecallback-ret-1-00000012;2", "Local/12812209483@from-internal,,") in new stack
[2023-01-17 20:34:18] VERBOSE[16155][C-0000000e] app_dial.c: Called Local/12812209483@from-internal
[2023-01-17 20:34:18] VERBOSE[16156][C-0000000e] pbx.c: Executing [12812209483@from-internal:1] ResetCDR("Local/12812209483@from-internal-00000013;2", "") in new stack
[2023-01-17 20:34:18] VERBOSE[16156][C-0000000e] pbx.c: Executing [12812209483@from-internal:2] NoCDR("Local/12812209483@from-internal-00000013;2", "") in new stack
[2023-01-17 20:34:18] VERBOSE[16156][C-0000000e] pbx.c: Executing [12812209483@from-internal:3] Progress("Local/12812209483@from-internal-00000013;2", "") in new stack
[2023-01-17 20:34:18] VERBOSE[16155][C-0000000e] app_dial.c: Local/12812209483@from-internal-00000013;1 is making progress passing it to Local/2812209483@queuecallback-ret-1-00000012;2
[2023-01-17 20:34:18] VERBOSE[16156][C-0000000e] pbx.c: Executing [12812209483@from-internal:4] Wait("Local/12812209483@from-internal-00000013;2", "1") in new stack
[2023-01-17 20:34:18] VERBOSE[16154] dial.c: Local/2812209483@queuecallback-ret-1-00000012;1 is making progress
[2023-01-17 20:34:19] VERBOSE[16156][C-0000000e] pbx.c: Executing [12812209483@from-internal:5] Playback("Local/12812209483@from-internal-00000013;2", "silence/1&cannot-complete-as-dialed&check-number-dial-again,noanswer") in new stack
[2023-01-17 20:34:19] VERBOSE[16156][C-0000000e] file.c: <Local/12812209483@from-internal-00000013;2> Playing 'silence/1.slin16' (language 'en')
[2023-01-17 20:34:20] VERBOSE[16156][C-0000000e] file.c: <Local/12812209483@from-internal-00000013;2> Playing 'cannot-complete-as-dialed.slin16' (language 'en')
[2023-01-17 20:34:21] VERBOSE[465] asterisk.c: Remote UNIX connection
[2023-01-17 20:34:21] VERBOSE[16165] asterisk.c: Remote UNIX connection disconnected
[2023-01-17 20:34:22] VERBOSE[15974][C-0000000d] pbx.c: Executing [request@queuecallback-1:21] Hangup("PJSIP/Telnyx_-_Durango-0000001a", "") in new stack
[2023-01-17 20:34:22] VERBOSE[15974][C-0000000d] pbx.c: Spawn extension (queuecallback-1, request, 21) exited non-zero on 'PJSIP/Telnyx_-_Durango-0000001a'
[2023-01-17 20:34:23] VERBOSE[16156][C-0000000e] file.c: <Local/12812209483@from-internal-00000013;2> Playing 'check-number-dial-again.slin16' (language 'en')
[2023-01-17 20:34:25] VERBOSE[16156][C-0000000e] pbx.c: Executing [12812209483@from-internal:6] Wait("Local/12812209483@from-internal-00000013;2", "1") in new stack
[2023-01-17 20:34:26] VERBOSE[16156][C-0000000e] pbx.c: Executing [12812209483@from-internal:7] Congestion("Local/12812209483@from-internal-00000013;2", "20") in new stack
[2023-01-17 20:34:26] VERBOSE[16155][C-0000000e] app_dial.c: Local/12812209483@from-internal-00000013;1 is circuit-busy
[2023-01-17 20:34:26] VERBOSE[16156][C-0000000e] pbx.c: Spawn extension (from-internal, 12812209483, 7) exited non-zero on 'Local/12812209483@from-internal-00000013;2'
[2023-01-17 20:34:26] VERBOSE[16155][C-0000000e] app_dial.c: Everyone is busy/congested at this time (1:0/1/0)
[2023-01-17 20:34:26] VERBOSE[16156][C-0000000e] pbx.c: Executing [h@from-internal:1] Macro("Local/12812209483@from-internal-00000013;2", "hangupcall") in new stack
[2023-01-17 20:34:26] VERBOSE[16155][C-0000000e] pbx.c: Executing [2812209483@queuecallback-ret-1:7] Set("Local/2812209483@queuecallback-ret-1-00000012;2", "SHARED(ANSWER_STATUS,Local/2812209483@queuecallback-ret-1-00000012;2)=") in new stack
[2023-01-17 20:34:26] VERBOSE[16155][C-0000000e] pbx.c: Executing [2812209483@queuecallback-ret-1:8] Set("Local/2812209483@queuecallback-ret-1-00000012;2", "__FORCE_CONFIRM=") in new stack
[2023-01-17 20:34:26] VERBOSE[16155][C-0000000e] pbx.c: Executing [2812209483@queuecallback-ret-1:9] NoOp("Local/2812209483@queuecallback-ret-1-00000012;2", "QUEUEREQUESTID : 1674009210.60") in new stack
[2023-01-17 20:34:26] VERBOSE[16155][C-0000000e] pbx.c: Executing [2812209483@queuecallback-ret-1:10] UserEvent("Local/2812209483@queuecallback-ret-1-00000012;2", "QueueCallbackRequestRemove,QUEUEREQUESTID: 1674009210.60") in new stack
[2023-01-17 20:34:26] VERBOSE[16155][C-0000000e] pbx.c: Auto fallthrough, channel 'Local/2812209483@queuecallback-ret-1-00000012;2' status is 'CONGESTION'
[2023-01-17 20:34:26] VERBOSE[16156][C-0000000e] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("Local/12812209483@from-internal-00000013;2", "1?theend") in new stack
[2023-01-17 20:34:26] VERBOSE[16154] dial.c: Local/2812209483@queuecallback-ret-1-00000012;1 is circuit-busy
[2023-01-17 20:34:26] VERBOSE[16156][C-0000000e] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2023-01-17 20:34:26] VERBOSE[16156][C-0000000e] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("Local/12812209483@from-internal-00000013;2", "0?Set(CDR(recordingfile)=)") in new stack
[2023-01-17 20:34:26] VERBOSE[16156][C-0000000e] pbx.c: Executing [s@macro-hangupcall:4] Hangup("Local/12812209483@from-internal-00000013;2", "") in new stack
[2023-01-17 20:34:26] VERBOSE[16156][C-0000000e] app_macro.c: Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'Local/12812209483@from-internal-00000013;2' in macro 'hangupcall'
[2023-01-17 20:34:26] VERBOSE[16156][C-0000000e] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on 'Local/12812209483@from-internal-00000013;2'


[2023-01-17 20:34:26] VERBOSE[16156][C-0000000e] pbx.c: Executing [12812209483@from-internal:7] Congestion("Local/12812209483@from-internal-00000013;2", "20") in new stack
[2023-01-17 20:34:26] VERBOSE[16155][C-0000000e] app_dial.c: Local/12812209483@from-internal-00000013;1 is circuit-busy
[2023-01-17 20:34:26] VERBOSE[16155][C-0000000e] app_dial.c: Everyone is busy/congested at this time (1:0/1/0)
[2023-01-17 20:34:26] VERBOSE[16155][C-0000000e] pbx.c: Auto fallthrough, channel 'Local/2812209483@queuecallback-ret-1-00000012;2' status is 'CONGESTION'
[2023-01-17 20:34:26] VERBOSE[16154] dial.c: Local/2812209483@queuecallback-ret-1-00000012;1 is circuit-busy

Its not making it out of the box then, do you have a matching outbound route?

I believe I do. Phones on the system can make outbound calls. But clearly the Local/12812209483@from-internal-00000013 appears that there is not. Is there another outbound route that nees to be set? or where would I specify an outbound route to use?

What’s your upstream dial tone provider? SIP or analog?

If it’s SIP is there any relevant log info that they can provide when a call fails like this?

It hasn’t failed at the provider. Asterisk has rejected it because, in FreePBX terms, there is no outbound route for 12812209483. Maybe the caller ID format differs from the format used for dialling?

Where would I set the caller ID to allow passage? With a phone on the system able to make outbound calls, it would seem the Callback queue as a different “rule” for outbound route. Is there a way to specify an outbound route for the queue?

Outbound Routes Module User Guide - PBX GUI - Documentation (freepbx.org)

Why don’t you share a screenshot of your outbound route(s) and dial patterns?


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