VERSION: 2.12-515a
BUILD: 151007-2224
ViciBox version 7.0
I'm having this issue on remote agent on outbound IVR. Vicidial calls out and reach the caller but only rings one or sometimes not, having a missed call status on the client.
here's the asterisk log, please see below
[Feb 20 15:57:09] == Manager 'sendcron' logged on from 127.0.0.1
[Feb 20 15:57:09] -- Executing [99153892085@default:1] AGI("Local/99153892085@default-0005cd82;2", "agi://127.0.0.1:4577/call_log") in new stack
[Feb 20 15:57:09] -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=OutIVR))
[Feb 20 15:57:09] -- <Local/99153892085@default-0005cd82;2>AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Feb 20 15:57:09] -- Executing [99153892085@default:2] Answer("Local/99153892085@default-0005cd82;2", "") in new stack
[Feb 20 15:57:09] > Channel Local/99153892085@default-0005cd82;1 was answered.
[Feb 20 15:57:09] -- Executing [8366@default:1] Playback("Local/99153892085@default-0005cd82;1", "sip-silence") in new stack
[Feb 20 15:57:09] -- <Local/99153892085@default-0005cd82;1> Playing 'sip-silence.gsm' (language 'en')
[Feb 20 15:57:09] -- Executing [99153892085@default:3] Set("Local/99153892085@default-0005cd82;2", "CALLERID(num)=027988000") in new stack
[Feb 20 15:57:09] -- Executing [99153892085@default:4] Dial("Local/99153892085@default-0005cd82;2", "SIP/09153892085@globe_sip,,To") in new stack
[Feb 20 15:57:09] == Using SIP RTP CoS mark 5
[Feb 20 15:57:09] -- Called SIP/09153892085@globe_sip
[Feb 20 15:57:09] -- SIP/globe_sip-00037056 is ringing
[Feb 20 15:57:09] -- Executing [8366@default:2] AGI("Local/99153892085@default-0005cd82;1", "agi://127.0.0.1:4577/call_log") in new stack
[Feb 20 15:57:09] -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=OutIVR))
[Feb 20 15:57:09] -- <Local/99153892085@default-0005cd82;1>AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Feb 20 15:57:09] -- Executing [8366@default:3] AGI("Local/99153892085@default-0005cd82;1", "agi-VDAD_ALL_outbound.agi,SURVEYCAMP-----LB") in new stack
[Feb 20 15:57:09] -- Launched AGI Script /usr/share/asterisk/agi-bin/agi-VDAD_ALL_outbound.agi
[Feb 20 15:57:10] == Manager 'sendcron' logged off from 127.0.0.1
[Feb 20 15:57:10] -- <Local/99153892085@default-0005cd82;1>AGI Script agi-VDAD_ALL_outbound.agi completed, returning 0
[Feb 20 15:57:10] -- Executing [8366@default:4] AGI("Local/99153892085@default-0005cd82;1", "agi-VDAD_ALL_outbound.agi,SURVEYCAMP-----LB") in new stack
[Feb 20 15:57:10] -- Launched AGI Script /usr/share/asterisk/agi-bin/agi-VDAD_ALL_outbound.agi
[Feb 20 15:57:11] -- <Local/99153892085@default-0005cd82;1>AGI Script agi-VDAD_ALL_outbound.agi completed, returning 0
[Feb 20 15:57:11] -- Executing [8366@default:5] Hangup("Local/99153892085@default-0005cd82;1", "") in new stack
[Feb 20 15:57:11] == Spawn extension (default, 8366, 5) exited non-zero on 'Local/99153892085@default-0005cd82;1'
[Feb 20 15:57:11] -- Executing [h@default:1] AGI("Local/99153892085@default-0005cd82;1", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16---------------") in new stack
[Feb 20 15:57:12] -- SIP/globe_sip-00037056 is ringing
[Feb 20 15:57:12] -- SIP/globe_sip-00037056 is making progress passing it to Local/99153892085@default-0005cd82;2
[Feb 20 15:57:12] -- <Local/99153892085@default-0005cd82;1>AGI Script agi://127.0.0.1:4577/call_log--HVcauses ... ---------- completed, returning 0
[Feb 20 15:57:12] -- Executing [h@default:2] Hangup("Local/99153892085@default-0005cd82;1", "") in new stack
[Feb 20 15:57:12] == Spawn extension (default, h, 2) exited non-zero on 'Local/99153892085@default-0005cd82;1'
[Feb 20 15:57:12] == Spawn extension (default, 99153892085, 4) exited non-zero on 'Local/99153892085@default-0005cd82;2'
[Feb 20 15:57:12] -- Executing [h@default:1] AGI("Local/99153892085@default-0005cd82;2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0-----CANCEL----------") in new stack
[Feb 20 15:57:13] -- <Local/99153892085@default-0005cd82;2>AGI Script agi://127.0.0.1:4577/call_log--HVcauses ... ---------- completed, returning 0
[Feb 20 15:57:13] -- Executing [h@default:2] Hangup("Local/99153892085@default-0005cd82;2", "") in new stack
[Feb 20 15:57:13] == Spawn extension (default, h, 2) exited non-zero on 'Local/99153892085@default-0005cd82;2'
[Feb 20 15:57:29] == Using SIP RTP CoS mark 5
-----EDIT--------
Here is the log when sip debug is on
- Code: Select all
<------------->
[Feb 20 17:09:07] --- (13 headers 0 lines) ---
[Feb 20 17:09:07]
<--- Transmitting (NAT) to 192.168.100.10:33187 --->
SIP/2.0 489 Bad Event
Via: SIP/2.0/UDP 192.168.100.10:33187;branch=z9hG4bK-524287-1---8dc8ea8539fa5f9d;received=192.168.100.10;rport=33187
From: <sip:3071@172.21.251.131;transport=UDP>;tag=82222245
To: <sip:3071@172.21.251.131;transport=UDP>;tag=as20d9aa99
Call-ID: tKOr-bqR2TW-Z8XnxVWSPg..
CSeq: 13 PUBLISH
Server: Asterisk PBX 1.8.32.3-vici
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Content-Length: 0
<------------>
[Feb 20 17:09:07] Really destroying SIP dialog 'tKOr-bqR2TW-Z8XnxVWSPg..' Method: PUBLISH
[Feb 20 17:09:07] -- <Local/99153892085@default-0005cdc7;1>AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------- completed, returning 0
[Feb 20 17:09:07] -- Executing [h@default:2] Hangup("Local/99153892085@default-0005cdc7;1", "") in new stack
[Feb 20 17:09:07] == Spawn extension (default, h, 2) exited non-zero on 'Local/99153892085@default-0005cdc7;1'
[Feb 20 17:09:07] Scheduling destruction of SIP dialog '2678e662734ef9c94b7e77ba4b187ed0@10.105.64.138:5060' in 6400 ms (Method: INVITE)
[Feb 20 17:09:07] Reliably Transmitting (NAT) to 10.105.64.137:5060:
CANCEL sip:09153892085@10.105.64.137 SIP/2.0
Via: SIP/2.0/UDP 10.105.64.138:5060;branch=z9hG4bK1e389599;rport
Max-Forwards: 70
From: "V2201709030005672646" <sip:027988000@10.105.64.138>;tag=as391cabe0
To: <sip:09153892085@10.105.64.137>
Call-ID: 2678e662734ef9c94b7e77ba4b187ed0@10.105.64.138:5060
CSeq: 102 CANCEL
User-Agent: Asterisk PBX 1.8.32.3-vici
Content-Length: 0
---
[Feb 20 17:09:07] Scheduling destruction of SIP dialog '2678e662734ef9c94b7e77ba4b187ed0@10.105.64.138:5060' in 6400 ms (Method: INVITE)
[Feb 20 17:09:07] == Spawn extension (default, 99153892085, 4) exited non-zero on 'Local/99153892085@default-0005cdc7;2'
[Feb 20 17:09:07] -- Executing [h@default:1] AGI("Local/99153892085@default-0005cdc7;2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0-----CANCEL----------") in new stack
[Feb 20 17:09:07]
<--- SIP read from UDP:10.105.64.137:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.105.64.138:5060;rport=5060;branch=z9hG4bK1e389599
To: <sip:09153892085@10.105.64.137>;tag=34ff44451d2d598a-1eedaae16e86f698-cbsxz
From: "V2201709030005672646"<sip:027988000@10.105.64.138>;tag=as391cabe0
Call-ID: 2678e662734ef9c94b7e77ba4b187ed0@10.105.64.138:5060
CSeq: 102 CANCEL
User-Agent: ZTE-SBC
X-ZTE-Cause: "SBC-4401-2002"
Content-Length: 0
<------------->
[Feb 20 17:09:07] --- (9 headers 0 lines) ---
[Feb 20 17:09:07]
<--- SIP read from UDP:10.105.64.137:5060 --->
SIP/2.0 487 Request Terminated
Via: SIP/2.0/UDP 10.105.64.138:5060;rport=5060;branch=z9hG4bK1e389599
To: <sip:09153892085@10.105.64.137>;tag=a6d0002-DOn837712def7
From: "V2201709030005672646"<sip:027988000@10.105.64.138>;tag=as391cabe0
Call-ID: 2678e662734ef9c94b7e77ba4b187ed0@10.105.64.138:5060
CSeq: 102 INVITE
User-Agent: ZTE Softswitch/1.0.0
Content-Length: 0
<------------->
[Feb 20 17:09:07] --- (8 headers 0 lines) ---
[Feb 20 17:09:07] Transmitting (NAT) to 10.105.64.137:5060:
ACK sip:09153892085@10.105.64.137:5060 SIP/2.0
Via: SIP/2.0/UDP 10.105.64.138:5060;branch=z9hG4bK1e389599;rport
Max-Forwards: 70
From: "V2201709030005672646" <sip:027988000@10.105.64.138>;tag=as391cabe0
To: <sip:09153892085@10.105.64.137>;tag=a6d0002-DOn837712def7
Contact: <sip:027988000@10.105.64.138:5060>
Call-ID: 2678e662734ef9c94b7e77ba4b187ed0@10.105.64.138:5060
CSeq: 102 ACK
User-Agent: Asterisk PBX 1.8.32.3-vici
Content-Length: 0
---
----------------------------------------------------
Always have this Cancel status. Please help as I have no other idea how to solve this. looking forward for your assistance
Thank you