Manual Dial works, Automated call don't pass to agents

[c20xh2]

I'm on a fresh install of this build:
VERSION: 2.14-895a
BUILD: 230830-1059

I'm able to do manual calls or using dial method Manual. When I use the Ratio dial method the call go out but the call is not transfer to the agent.

I have these entry in extensions.conf to make sure the number show correctly with canada numbers:

Code: Select all

[loopback-no-log]
; This context is to accept calls that have already been logged in another context in Vicidial
; and has been sent through one of the loopbacks. This is why this context is missing the h extension.
; Do not put any extensions in this context unless you specifically understand what this means.

;exten => _91NXXNXXXXXX,1,Dial(${TRUNKX}/${EXTEN:1},,To)
;exten => _91NXXNXXXXXX,n,Hangup()

; special Canadian PRI callerIDname settings FOR USE IN LOOPBACK CONTEXT ONLY
exten => _91NXXNXXXXXX,1,Set(CALLERID(name)=${CALLERID(num)})
exten => _91NXXNXXXXXX,n,AGI(agi-CANADA_PRI_CIDname.agi)
exten => _91NXXNXXXXXX,n,Dial(${TRUNK}/${EXTEN:1},,To)
exten => _91NXXNXXXXXX,n,Hangup()

exten => _999XX11112,1,Wait(2)
exten => _999XX11112,n,Answer()
exten => _999XX11112,n,Playback(ss-noservice)
exten => _999XX11112,n,Playback(vm-goodbye)
exten => _999XX11112,n,Hangup()


Code: Select all

; dial a USA long distance outbound number through the loopback-no-log context
exten => _91NXXNXXXXXX,1,AGI(agi://127.0.0.1:4577/call_log)
exten => _91NXXNXXXXXX,n,Dial(${TRUNKloop}/888${EXTEN:2},55,o)
exten => _91NXXNXXXXXX,n,Hangup()
exten => _888NXXNXXXXXX,1,Goto(loopback-no-log,91${EXTEN:3},1)
exten => _888NXXNXXXXXX,n,Hangup()

exten => 8889990011112,1,Goto(loopback-no-log,9990011112,1)
exten => 8889990011112,n,Hangup()


This is my dial plan:

Code: Select all

exten => _6NXXNXXXXXX,1,AGI(agi://127.0.0.1:4577/call_log)
exten => _6NXXNXXXXXX,2,Dial(${TRUNK}/${EXTEN:1},,tTor)
exten => _6NXXNXXXXXX,3,Hangup

exten => _61NXXNXXXXXX,1,AGI(agi://127.0.0.1:4577/call_log)
exten => _61NXXNXXXXXX,2,Dial(${TRUNK}/${EXTEN:2},,tTor)
exten => _61NXXNXXXXXX,3,Hangup


This call was passed to the agent, everything works well

Code: Select all

####### WORKING MANUAL CALL #######
[Sep 18 14:10:28]     -- Called 8600051@default
[Sep 18 14:10:28]     -- Executing [8600051@default:1] MeetMe("Local/8600051@default-0000000b;2", "8600051,F") in new stack
[Sep 18 14:10:28]     -- Local/8600051@default-0000000b;1 answered
[Sep 18 14:10:28]     -- Executing [914389291847@default:1] AGI("Local/8600051@default-0000000b;1", "agi://127.0.0.1:4577/call_log") in new stack
[Sep 18 14:10:28]     -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=1000))
[Sep 18 14:10:28]     -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPDTO=60))
[Sep 18 14:10:28]     -- <Local/8600051@default-0000000b;1>AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Sep 18 14:10:28]     -- Executing [914389291847@default:2] Dial("Local/8600051@default-0000000b;1", "IAX2/ASTloop:Wp4lsLgY0R9Mebw@127.0.0.1:40569/8884389291847,55,o") in new stack
[Sep 18 14:10:28]     -- Called IAX2/ASTloop:Wp4lsLgY0R9Mebw@127.0.0.1:40569/8884389291847
[Sep 18 14:10:28]     -- Accepting AUTHENTICATED call from 127.0.0.1:42745:
[Sep 18 14:10:28]     --        > requested format = ulaw,
[Sep 18 14:10:28]     --        > requested prefs = (ulaw|gsm),
[Sep 18 14:10:28]     --        > actual format = ulaw,
[Sep 18 14:10:28]     --        > host prefs = (ulaw),
[Sep 18 14:10:28]     --        > priority = mine
[Sep 18 14:10:28]     -- Executing [8884389291847@default:1] Goto("IAX2/ASTloop-4608", "loopback-no-log,914389291847,1") in new stack
[Sep 18 14:10:28]     -- Call accepted by 127.0.0.1:40569 (format ulaw)
[Sep 18 14:10:28]     -- Format for call is (ulaw)
[Sep 18 14:10:28]     -- Goto (loopback-no-log,914389291847,1)
[Sep 18 14:10:28]     -- Executing [914389291847@loopback-no-log:1] Set("IAX2/ASTloop-4608", "CALLERID(name)=4388843573") in new stack
[Sep 18 14:10:28]     -- Executing [914389291847@loopback-no-log:2] AGI("IAX2/ASTloop-4608", "agi-CANADA_PRI_CIDname.agi") in new stack
[Sep 18 14:10:28]     -- Launched AGI Script /usr/share/asterisk/agi-bin/agi-CANADA_PRI_CIDname.agi
[Sep 18 14:10:28]     -- <IAX2/ASTloop-4608>AGI Script agi-CANADA_PRI_CIDname.agi completed, returning 0
[Sep 18 14:10:28]     -- Executing [914389291847@loopback-no-log:3] Dial("IAX2/ASTloop-4608", "SIP/voipms/14389291847,,To") in new stack
[Sep 18 14:10:28]   == Using SIP RTP CoS mark 5
[Sep 18 14:10:28]     -- Called SIP/voipms/14389291847
[Sep 18 14:10:30]        > 0x7f3af4016bd0 -- Strict RTP learning after remote address set to: 208.100.60.27:14864
[Sep 18 14:10:30]     -- SIP/voipms-0000000b is making progress passing it to IAX2/ASTloop-4608
[Sep 18 14:10:30]     -- Dial Tiemout Reset on channel SIP/voipms-0000000b due to PROGRESS message.
[Sep 18 14:10:30]        > 0x7f3af4016bd0 -- Strict RTP switching to RTP target address 208.100.60.27:14864 as source
[Sep 18 14:10:30]     -- IAX2/127.0.0.1:40569-1797 is making progress passing it to Local/8600051@default-0000000b;1
[Sep 18 14:10:30]     -- Dial Tiemout Reset on channel IAX2/127.0.0.1:40569-1797 due to PROGRESS message.
[Sep 18 14:10:33]     -- SIP/voipms-0000000b requested media update control 26, passing it to IAX2/ASTloop-4608
[Sep 18 14:10:33]     -- SIP/voipms-0000000b answered IAX2/ASTloop-4608
[Sep 18 14:10:33]     -- Channel SIP/voipms-0000000b joined 'simple_bridge' basic-bridge <76270c82-63bc-4c80-bc4e-b4c82c1c0dda>
[Sep 18 14:10:33]     -- Channel IAX2/ASTloop-4608 joined 'simple_bridge' basic-bridge <76270c82-63bc-4c80-bc4e-b4c82c1c0dda>
[Sep 18 14:10:33]     -- IAX2/127.0.0.1:40569-1797 answered Local/8600051@default-0000000b;1
[Sep 18 14:10:33]     -- Channel IAX2/127.0.0.1:40569-1797 joined 'simple_bridge' basic-bridge <37f305dc-eab9-4572-b05c-6a072f23ed82>
[Sep 18 14:10:33]     -- Channel Local/8600051@default-0000000b;1 joined 'simple_bridge' basic-bridge <37f305dc-eab9-4572-b05c-6a072f23ed82>
[Sep 18 14:10:35]        > 0x7f3af4016bd0 -- Strict RTP learning complete - Locking on source address 208.100.60.27:14864
[Sep 18 14:10:37]     -- Channel SIP/voipms-0000000b left 'simple_bridge' basic-bridge <76270c82-63bc-4c80-bc4e-b4c82c1c0dda>
[Sep 18 14:10:37]     -- Channel IAX2/ASTloop-4608 left 'simple_bridge' basic-bridge <76270c82-63bc-4c80-bc4e-b4c82c1c0dda>
[Sep 18 14:10:37]   == Spawn extension (loopback-no-log, 914389291847, 3) exited non-zero on 'IAX2/ASTloop-4608'
[Sep 18 14:10:37]     -- Hungup 'IAX2/ASTloop-4608'
[Sep 18 14:10:37]     -- Channel IAX2/127.0.0.1:40569-1797 left 'simple_bridge' basic-bridge <37f305dc-eab9-4572-b05c-6a072f23ed82>
[Sep 18 14:10:37]     -- Channel Local/8600051@default-0000000b;1 left 'simple_bridge' basic-bridge <37f305dc-eab9-4572-b05c-6a072f23ed82>
[Sep 18 14:10:37]   == Spawn extension (default, 914389291847, 2) exited non-zero on 'Local/8600051@default-0000000b;1'
[Sep 18 14:10:37]     -- Executing [h@default:1] AGI("Local/8600051@default-0000000b;1", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----8-----8-----IAX2 HANGUP (16))") in new stack
[Sep 18 14:10:37]     -- Hungup 'IAX2/127.0.0.1:40569-1797'
[Sep 18 14:10:37]     -- <Local/8600051@default-0000000b;1>AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----8-----8-----IAX2 HANGUP (16)) completed, returning 0
[Sep 18 14:10:37]   == Spawn extension (default, 8600051, 1) exited non-zero on 'Local/8600051@default-0000000b;2'
[Sep 18 14:10:37] WARNING[7596][C-0000001c]: func_hangupcause.c:138 hangupcause_read: Unable to find information for channel
[Sep 18 14:10:37]     -- Executing [h@default:1] AGI("Local/8600051@default-0000000b;2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------------)") in new stack
[Sep 18 14:10:37]     -- <Local/8600051@default-0000000b;2>AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------------) completed, returning 0



This call ring the cellphone, I can answer but it's not pass to the agent.

Code: Select all

####### NOT WORKING RATIO CALL #######
[Sep 18 14:13:00]     -- Called 914389291847@default
[Sep 18 14:13:00]     -- Executing [914389291847@default:1] AGI("Local/914389291847@default-0000000c;2", "agi://127.0.0.1:4577/call_log") in new stack
[Sep 18 14:13:00]     -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=1000))
[Sep 18 14:13:00]     -- <Local/914389291847@default-0000000c;2>AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Sep 18 14:13:00]     -- Executing [914389291847@default:2] Dial("Local/914389291847@default-0000000c;2", "IAX2/ASTloop:Wp4lsLgY0R9Mebw@127.0.0.1:40569/8884389291847,55,o") in new stack
[Sep 18 14:13:00]     -- Called IAX2/ASTloop:Wp4lsLgY0R9Mebw@127.0.0.1:40569/8884389291847
[Sep 18 14:13:00]     -- Accepting AUTHENTICATED call from 127.0.0.1:42745:
[Sep 18 14:13:00]     --        > requested format = ulaw,
[Sep 18 14:13:00]     --        > requested prefs = (ulaw|gsm),
[Sep 18 14:13:00]     --        > actual format = ulaw,
[Sep 18 14:13:00]     --        > host prefs = (ulaw),
[Sep 18 14:13:00]     --        > priority = mine
[Sep 18 14:13:00]     -- Call accepted by 127.0.0.1:40569 (format ulaw)
[Sep 18 14:13:00]     -- Format for call is (ulaw)
[Sep 18 14:13:00]     -- Executing [8884389291847@default:1] Goto("IAX2/ASTloop-5215", "loopback-no-log,914389291847,1") in new stack
[Sep 18 14:13:00]     -- Goto (loopback-no-log,914389291847,1)
[Sep 18 14:13:00]     -- Executing [914389291847@loopback-no-log:1] Set("IAX2/ASTloop-5215", "CALLERID(name)=4388843573") in new stack
[Sep 18 14:13:00]     -- Executing [914389291847@loopback-no-log:2] AGI("IAX2/ASTloop-5215", "agi-CANADA_PRI_CIDname.agi") in new stack
[Sep 18 14:13:00]     -- Launched AGI Script /usr/share/asterisk/agi-bin/agi-CANADA_PRI_CIDname.agi
[Sep 18 14:13:00]     -- <IAX2/ASTloop-5215>AGI Script agi-CANADA_PRI_CIDname.agi completed, returning 0
[Sep 18 14:13:00]     -- Executing [914389291847@loopback-no-log:3] Dial("IAX2/ASTloop-5215", "SIP/voipms/14389291847,,To") in new stack
[Sep 18 14:13:00]   == Using SIP RTP CoS mark 5
[Sep 18 14:13:00]     -- Called SIP/voipms/14389291847
[Sep 18 14:13:01]        > 0x7f3b3c0109c0 -- Strict RTP learning after remote address set to: 208.100.60.27:15480
[Sep 18 14:13:01]     -- SIP/voipms-0000000c is making progress passing it to IAX2/ASTloop-5215
[Sep 18 14:13:01]     -- Dial Tiemout Reset on channel SIP/voipms-0000000c due to PROGRESS message.
[Sep 18 14:13:08]     -- SIP/voipms-0000000c answered IAX2/ASTloop-5215
[Sep 18 14:13:08]     -- Channel SIP/voipms-0000000c joined 'simple_bridge' basic-bridge <4883e72f-ccda-4df6-a5c9-5e2253253ec7>
[Sep 18 14:13:08]     -- Channel IAX2/ASTloop-5215 joined 'simple_bridge' basic-bridge <4883e72f-ccda-4df6-a5c9-5e2253253ec7>
[Sep 18 14:13:14]     -- Channel SIP/voipms-0000000c left 'simple_bridge' basic-bridge <4883e72f-ccda-4df6-a5c9-5e2253253ec7>
[Sep 18 14:13:14]     -- Channel IAX2/ASTloop-5215 left 'simple_bridge' basic-bridge <4883e72f-ccda-4df6-a5c9-5e2253253ec7>
[Sep 18 14:13:14]   == Spawn extension (loopback-no-log, 914389291847, 3) exited non-zero on 'IAX2/ASTloop-5215'
[Sep 18 14:13:14]     -- Hungup 'IAX2/ASTloop-5215'
[Sep 18 14:13:14]     -- Hungup 'IAX2/127.0.0.1:40569-1699'
[Sep 18 14:13:14]     -- No one is available to answer at this time (1:0/0/0)
[Sep 18 14:13:14]     -- Executing [914389291847@default:3] Hangup("Local/914389291847@default-0000000c;2", "") in new stack
[Sep 18 14:13:14]   == Spawn extension (default, 914389291847, 3) exited non-zero on 'Local/914389291847@default-0000000c;2'
[Sep 18 14:13:14]     -- Executing [h@default:1] AGI("Local/914389291847@default-0000000c;2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----NOANSWER---------------IAX2 HANGUP (16))") in new stack
[Sep 18 14:13:14]     -- <Local/914389291847@default-0000000c;2>AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----NOANSWER---------------IAX2 HANGUP (16)) completed, returning 0


What am I missing ?

[carpenox]

Is it a cluster or single? How many agents? What's the average dial ratio?

[c20xh2]

For now it's a single box with only one agent. The ratio is set to 1.

I just did a reinstall using the last version I used a couple of years ago:
VERSION: 2.14-718a
BUILD: 190902-0839
ViciBox_v9.x86_64-9.0.0.iso
Asterisk Version 13.27.0-vici
SVN Version: 3133

And everything works perfectly with the same dial plan, extensions.conf modification and carrier that I was using on the latest version.

Is it possible that there is a bug in the latest version when you use the agi-CANADA_PRI_CIDname.agi ?

[carpenox]

I can give you strikers loopback to fix it:

exten => _81NXXNXXXXXX,1,AGI(agi://127.0.0.1:4577/call_log)
exten => _81NXXNXXXXXX,n,NoOp(MY CALLERD !!!! ${CALLERID(all)})
exten => _81NXXNXXXXXX,n,Set(_CALLERID(num)=${CALLERID(num)})
exten => _81NXXNXXXXXX,n,Dial(IAX2/ASTloop/9${EXTEN:1},,tTo)
exten => _81NXXNXXXXXX,n,Hangup()

exten => _91NXXNXXXXXX,1,Set(CALLERID(num)=${CALLERID(num)})
exten => _91NXXNXXXXXX,n,Set(CALLERID(name)=${CALLERID(num)})
exten => _91NXXNXXXXXX,n,NoOp(MY CALLERD !!!! ${CALLERID(all)} ${CALLERID(num)} ${CALLERID(name)})
exten => _91NXXNXXXXXX,n,Dial(SIP/${EXTEN:1}@TILTX,,tTo)
exten => _91NXXNXXXXXX,n,Hangup()

Add that to your extensions

Hope that helps