Page 1 of 1

ONHOOK FAILING AFTER UPDATE

PostPosted: Wed Mar 18, 2020 3:16 pm
by covarrubiasgg
Hello all

I have a cluster that has been working since 2012, we do regular upgrades to it. This weekend we upgrade Asterisk BUILD TO SVN VERSION: 2.14-740a BUILD: 200315-1126

After the upgrade, we started having a weird issue with our On-Hook extensions. With INBOUND CALLS while the customer is hearing the HOLD PROMPT or the WELCOME MESSAGE the Agent's phone RINGS but the call does not connect. I will try to be more specific

Scenario 1 .- Answer before the hold prompt

1.- I Call the inbound number. The DID routes the call to the inbound group. The Welcome message is disabled.
2.- The Agent's Phone rings.
3.- Agent Answer the Softphone
4.- Call connects and both agent and customer can talk


Scenario 2 .- Answer while the hold prompt

1.- I Call the inbound number. The DID routes the call to the inbound group. The Welcome message is disabled.
2.- The Agent's Phone rings.
3.- The Customer hears the HOLD MESSAGE
4.- Agent Answer the Softphone
At this point is where it fails
5.a - Agent Answer the calls, but the customer never "lands" into the conference
5.b - Customer keep hearing the HOLD MESSAGE until it ends and start hearing music on hold.
5.c - While Customer hears HOLD MESSAGE the call while do round robin among all the extension on that inbound group.
6 .- Once the HOLD MESSAGE finish, the call will land to an Agent as usual.

I tried to disabled the HOLD MESSAGE and us WELCOME MESSAGE instead but it has the same behaviour.

If we switch the extensions from On-Hook to regular extension, everything works normal.

We have already tried using Web-phone instead of eye-beam, same result.

Now we are working without Hold or Welcome Message and everything is working good but we cant be working only with Music On Hold.

Any ideas? Or ar least someone to replicate the problem?

VICIDIAL CLUSTER 6 x Vicibox 9.0.1 Telephony Servers | 3 x Vicibox 9.0.1 Web Servers | 1 x Vicibox 8.2 Database Server | 1 x Vicibox 9.0.1 Nginx Load Balancer

Re: ONHOOK FAILING AFTER UPDATE

PostPosted: Thu Mar 19, 2020 8:04 am
by mflorell
Any errors show up in the Asterisk CLI?

Can you post the agiout logfile output when this happens?

Re: ONHOOK FAILING AFTER UPDATE

PostPosted: Fri Mar 20, 2020 2:35 pm
by covarrubiasgg
mflorell wrote:Any errors show up in the Asterisk CLI?

Can you post the agiout logfile output when this happens?


In the asterisk CLI there were no errors,,, i just watch the Phone Ringing and then HangUp

Thi is the FASTAGI LOG from a call that failed


NOTE phone number were replaced with ********** for privacy

Code: Select all
2020-03-17 20:43:27|VDfastAGI|begin|+++++++++++++++++ FastAGI Start ++++ Asterisk version: 1 13 ++++++ hER: 1 ++++++
2020-03-17 20:43:27|VDfastAGI|begin|Perl Environment Dump:
2020-03-17 20:43:27|VDfastAGI|begin|0|--debug
2020-03-17 20:43:27|VDfastAGI|begin|URL HVcauses: |PRI|NODEBUG|16|||0|SIP 200 OK)|
2020-03-17 20:43:27|VDfastAGI|begin|AGI Environment Dump:
2020-03-17 20:43:27|VDfastAGI|begin| -- accountcode = 2093
2020-03-17 20:43:27|VDfastAGI|begin| -- callerid = **********
2020-03-17 20:43:27|VDfastAGI|begin| -- calleridname = ACagcW1584502995c4techc4techc4techc4tech
2020-03-17 20:43:27|VDfastAGI|begin| -- callingani2 = 0
2020-03-17 20:43:27|VDfastAGI|begin| -- callingpres = 0
2020-03-17 20:43:27|VDfastAGI|begin| -- callingtns = 0
2020-03-17 20:43:27|VDfastAGI|begin| -- callington = 0
2020-03-17 20:43:27|VDfastAGI|begin| -- channel = SIP/2093-00000025
2020-03-17 20:43:27|VDfastAGI|begin| -- context = default
2020-03-17 20:43:27|VDfastAGI|begin| -- dnid = unknown
2020-03-17 20:43:27|VDfastAGI|begin| -- enhanced = 0.0
2020-03-17 20:43:27|VDfastAGI|begin| -- extension = h
2020-03-17 20:43:27|VDfastAGI|begin| -- language = en
2020-03-17 20:43:27|VDfastAGI|begin| -- network = yes
2020-03-17 20:43:27|VDfastAGI|begin| -- network_script = call_log--HVcauses--PRI-----NODEBUG-----16--------------------SIP 200 OK)
2020-03-17 20:43:27|VDfastAGI|begin| -- priority = 1
2020-03-17 20:43:27|VDfastAGI|begin| -- rdnis = unknown
2020-03-17 20:43:27|VDfastAGI|begin| -- request = agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------------SIP 200 OK)
2020-03-17 20:43:27|VDfastAGI|begin| -- threadid = 139985474586368
2020-03-17 20:43:27|VDfastAGI|begin| -- type = SIP
2020-03-17 20:43:27|VDfastAGI|begin| -- uniqueid = 1584502996.157
2020-03-17 20:43:27|VDfastAGI|begin| -- version = 13.29.2-vici
2020-03-17 20:43:27|VDfastAGI|begin|AGI Variables: |1584502996.157|SIP/2093-00000025|h|SIP|**********|
2020-03-17 20:43:27|VDfastAGI|call_log|Process to run: |agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------------SIP 200 OK)|call_log|END|
2020-03-17 20:43:27|VDfastAGI|call_log||CALL HUNG UP|
2020-03-17 20:43:27|VDfastAGI|call_log|URL HVcauses: |PRI|NODEBUG|16|||0|SIP 200 OK)|
2020-03-17 20:43:27|VDfastAGI|call_log||DELETE from live_inbound where uniqueid IN('1584502996.157','1584502996.157') and server_ip='192.168.100.51'|
2020-03-17 20:43:27|VDfastAGI|call_log|+++++ CALL LOG HUNGUP: |1584502996.157|SIP/2093-00000025|h|2020-03-17 20:43:27|min: |
2020-03-17 20:43:27|VDfastAGI|call_log|DEBUG: NODEBUG
2020-03-17 20:43:27|VDfastAGI|call_log|VD_hangup : ********** SIP/2093-00000025 1 0
2020-03-17 20:43:27|VDfastAGI|call_log||SELECT lead_id,callerid,campaign_id,alt_dial,stage,UNIX_TIMESTAMP(call_time),uniqueid,status,call_time,phone_code,phone_number,queue_position,server_ip,agent_only FROM vicidial_auto_calls where uniqueid = '1584502996.157' or callerid = '**********' limit 1;|
2020-03-17 20:43:27|VDfastAGI|call_log|VD hangup: no VDAC record found: 1584502996.157 **********


I am not very failiar with this log file, but it there are some lines that comes to my sight

2020-03-17 20:43:27|VDfastAGI|call_log|Process to run: |agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------------SIP 200 OK)|call_log|END|
2020-03-17 20:43:27|VDfastAGI|call_log||CALL HUNG UP|
2020-03-17 20:43:27|VDfastAGI|call_log|URL HVcauses: |PRI|NODEBUG|16|||0|SIP 200 OK)|
2020-03-17 20:43:27|VDfastAGI|call_log||DELETE from live_inbound where uniqueid IN('1584502996.157','1584502996.157') and server_ip='192.168.100.51'|



Then

2020-03-17 20:43:27|VDfastAGI|call_log||SELECT lead_id,callerid,campaign_id,alt_dial,stage,UNIX_TIMESTAMP(call_time),uniqueid,status,call_time,phone_code,phone_number,queue_position,server_ip,agent_only FROM vicidial_auto_calls where uniqueid = '1584502996.157' or callerid = '**********' limit 1;|
2020-03-17 20:43:27|VDfastAGI|call_log|VD hangup: no VDAC record found: 1584502996.157 **********