Survey campaign call gets hangup after getting DTMF

All installation and configuration problems and questions

Moderators: gerski, enjay, williamconley, Op3r, Staydog, gardo, mflorell, MJCoate, mcargile, Kumba, Michael_N

Survey campaign call gets hangup after getting DTMF

Postby mubeen » Mon Oct 01, 2018 9:32 am

Hi,

Asterisk 11.25.3-vici built by abuild @ lamb21 on a x86_64
VERSION: 2.14-667a
BUILD: 180331-1715
ViciBox v.8.0.1

I have setup Press-1 Campaign. Issue I'm facing is most of the time (I do receive calls when testing with my number) the calllee presses a number, I do receive DTMF but call drops after "buzz" sound. Pasted below CLI output of both calls, one which was dropped, other test call which I was able to receive

I have been working on this from last 3 days but couldn't figure out the issue. Not sure why "buzz" is played instead of running agi

CALL DROPPED

Code: Select all
[Oct  1 09:42:05] VERBOSE[27262][C-0009b15f] pbx.c: [Oct  1 09:42:05]     -- Executing [91305xxxxxxx@default:1] AGI("Local/91305xxxxxxx@default-0008257c;2", "agi://127.0.0.1:4577/call_log") in new stack
[Oct  1 09:42:05] VERBOSE[27262][C-0009b15f] res_agi.c: [Oct  1 09:42:05]     -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=HS_IVR_2))
[Oct  1 09:42:05] VERBOSE[27262][C-0009b15f] res_agi.c: [Oct  1 09:42:05]     -- <Local/91305xxxxxxx@default-0008257c;2>AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Oct  1 09:42:05] VERBOSE[27262][C-0009b15f] pbx.c: [Oct  1 09:42:05]     -- Executing [91305xxxxxxx@default:2] Dial("Local/91305xxxxxxx@default-0008257c;2", "sip/1305xxxxxxx@Carrier,55,tTor") in new stack
[Oct  1 09:42:05] VERBOSE[27262][C-0009b15f] netsock2.c: [Oct  1 09:42:05]   == Using SIP RTP CoS mark 5
[Oct  1 09:42:05] VERBOSE[27262][C-0009b15f] app_dial.c: [Oct  1 09:42:05]     -- Called sip/1305xxxxxxx@Carrier
[Oct  1 09:42:06] VERBOSE[27262][C-0009b15f] app_dial.c: [Oct  1 09:42:06]     -- SIP/Carrier-000699c0 is ringing
[Oct  1 09:42:06] VERBOSE[20030][C-0009b15f] res_rtp_asterisk.c: [Oct  1 09:42:06]        > 0x7f805000ab30 -- Strict RTP learning after remote address set to: 169.132.xxx.xxx:23666
[Oct  1 09:42:06] VERBOSE[27262][C-0009b15f] app_dial.c: [Oct  1 09:42:06]     -- SIP/Carrier-000699c0 is making progress passing it to Local/91305xxxxxxx@default-0008257c;2
[Oct  1 09:42:06] VERBOSE[27262][C-0009b15f] res_rtp_asterisk.c: [Oct  1 09:42:06]        > 0x7f805000ab30 -- Strict RTP switching to RTP remote address 169.132.xxx.xxx:23666 as source
[Oct  1 09:42:08] VERBOSE[27262][C-0009b15f] res_rtp_asterisk.c: [Oct  1 09:42:08]        > 0x7f805000ab30 -- Strict RTP learning complete - Locking on source address 169.132.xxx.xxx:23666
[Oct  1 09:42:16] VERBOSE[27262][C-0009b15f] app_dial.c: [Oct  1 09:42:16]     -- SIP/Carrier-000699c0 answered Local/91305xxxxxxx@default-0008257c;2
[Oct  1 09:42:16] VERBOSE[27261][C-0009b15f] pbx.c: [Oct  1 09:42:16]        > Channel Local/91305xxxxxxx@default-0008257c;1 was answered
[Oct  1 09:42:16] VERBOSE[27504][C-0009b15f] pbx.c: [Oct  1 09:42:16]     -- Executing [8366@default:1] Playback("SIP/Carrier-000699c0", "sip-silence") in new stack
[Oct  1 09:42:16] VERBOSE[27262][C-0009b15f] pbx.c: [Oct  1 09:42:16]     -- Executing [h@default:1] AGI("Local/91305xxxxxxx@default-0008257c;2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----11-----0") in new stack
[Oct  1 09:42:16] VERBOSE[27504][C-0009b15f] file.c: [Oct  1 09:42:16]     -- <SIP/Carrier-000699c0> Playing 'sip-silence.gsm' (language 'en')
[Oct  1 09:42:16] VERBOSE[27504][C-0009b15f] pbx.c: [Oct  1 09:42:16]     -- Executing [8366@default:2] AGI("SIP/Carrier-000699c0", "agi://127.0.0.1:4577/call_log") in new stack
[Oct  1 09:42:16] VERBOSE[27504][C-0009b15f] res_agi.c: [Oct  1 09:42:16]     -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=HS_IVR_2))
[Oct  1 09:42:16] VERBOSE[27504][C-0009b15f] res_agi.c: [Oct  1 09:42:16]     -- <SIP/Carrier-000699c0>AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Oct  1 09:42:16] VERBOSE[27504][C-0009b15f] pbx.c: [Oct  1 09:42:16]     -- Executing [8366@default:3] AGI("SIP/Carrier-000699c0", "agi-VDAD_ALL_outbound.agi,SURVEYCAMP-----LB") in new stack
[Oct  1 09:42:16] VERBOSE[27504][C-0009b15f] res_agi.c: [Oct  1 09:42:16]     -- Launched AGI Script /usr/share/asterisk/agi-bin/agi-VDAD_ALL_outbound.agi
[Oct  1 09:42:16] VERBOSE[27504][C-0009b15f] res_agi.c: [Oct  1 09:42:16]     -- AGI Script Executing Application: (Monitor) Options: (wav,/var/spool/asterisk/monitor/MIX/20181001-094216_305xxxxxxx)
[Oct  1 09:42:16] VERBOSE[27504][C-0009b15f] res_agi.c: [Oct  1 09:42:16]     -- <SIP/Carrier-000699c0> Playing 'sip-silence.gsm' (escape_digits=) (sample_offset 0) (language 'en')
[Oct  1 09:42:16] VERBOSE[27504][C-0009b15f] res_agi.c: [Oct  1 09:42:16]     -- <SIP/Carrier-000699c0> Playing 'OUT-8khz.slin' (escape_digits=123) (sample_offset 0) (language 'en')
[Oct  1 09:42:17] VERBOSE[27262][C-0009b15f] res_agi.c: [Oct  1 09:42:17]     -- <Local/91305xxxxxxx@default-0008257c;2>AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----11-----0 completed, returning 0
[Oct  1 09:42:17] VERBOSE[27262][C-0009b15f] pbx.c: [Oct  1 09:42:17]   == Spawn extension (default, 91305xxxxxxx, 2) exited non-zero on 'Local/91305xxxxxxx@default-0008257c;2'
[Oct  1 09:42:27] DTMF[27504][C-0009b15f] channel.c: DTMF begin '1' received on SIP/Carrier-000699c0
[Oct  1 09:42:27] DTMF[27504][C-0009b15f] channel.c: DTMF begin ignored '1' on SIP/Carrier-000699c0
[Oct  1 09:42:27] DTMF[27504][C-0009b15f] channel.c: DTMF end '1' received on SIP/Carrier-000699c0, duration 180 ms
[Oct  1 09:42:27] DTMF[27504][C-0009b15f] channel.c: DTMF end passthrough '1' on SIP/Carrier-000699c0
[Oct  1 09:42:33] VERBOSE[27504][C-0009b15f] res_agi.c: [Oct  1 09:42:33]     -- <SIP/Carrier-000699c0> Playing 'sip-silence.gsm' (escape_digits=) (sample_offset 0) (language 'en')
[Oct  1 09:42:33] VERBOSE[27504][C-0009b15f] res_agi.c: [Oct  1 09:42:33]     -- <SIP/Carrier-000699c0> Playing 'sip-silence.gsm' (escape_digits=) (sample_offset 0) (language 'en')
[Oct  1 09:42:33] VERBOSE[27504][C-0009b15f] res_agi.c: [Oct  1 09:42:33]     -- <SIP/Carrier-000699c0> Playing 'buzz.gsm' (escape_digits=) (sample_offset 0) (language 'en')
[Oct  1 09:42:33] VERBOSE[27504][C-0009b15f] res_agi.c: [Oct  1 09:42:33]     -- <SIP/Carrier-000699c0>AGI Script agi-VDAD_ALL_outbound.agi completed, returning 4
[Oct  1 09:42:33] VERBOSE[27504][C-0009b15f] pbx.c: [Oct  1 09:42:33]   == Spawn extension (default, 8366, 3) exited non-zero on 'SIP/Carrier-000699c0'
[Oct  1 09:42:33] VERBOSE[27504][C-0009b15f] pbx.c: [Oct  1 09:42:33]     -- Executing [h@default:1] AGI("SIP/Carrier-000699c0", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0---------------") in new stack
[Oct  1 09:42:33] VERBOSE[27504][C-0009b15f] res_agi.c: [Oct  1 09:42:33]     -- <SIP/Carrier-000699c0>AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0--------------- completed, returning 0



TEST CALL I RECEIVED
Code: Select all
[Oct  1 07:54:32] VERBOSE[7553][C-0009ad94] pbx.c: [Oct  1 07:54:32]     -- Executing [91909xxxxxxx@default:1] AGI("Local/91909xxxxxxx@default-000821c6;2", "agi://127.0.0.1:4577/call_log") in new stack
[Oct  1 07:54:32] VERBOSE[7553][C-0009ad94] res_agi.c: [Oct  1 07:54:32]     -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=HS_IVR_2))
[Oct  1 07:54:32] VERBOSE[7553][C-0009ad94] res_agi.c: [Oct  1 07:54:32]     -- <Local/91909xxxxxxx@default-000821c6;2>AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Oct  1 07:54:32] VERBOSE[7553][C-0009ad94] pbx.c: [Oct  1 07:54:32]     -- Executing [91909xxxxxxx@default:2] Dial("Local/91909xxxxxxx@default-000821c6;2", "sip/1909xxxxxxx@Carrier,55,tTor") in new stack
[Oct  1 07:54:32] VERBOSE[7553][C-0009ad94] netsock2.c: [Oct  1 07:54:32]   == Using SIP RTP CoS mark 5
[Oct  1 07:54:32] VERBOSE[7553][C-0009ad94] app_dial.c: [Oct  1 07:54:32]     -- Called sip/1909xxxxxxx@Carrier
[Oct  1 07:54:36] VERBOSE[20030][C-0009ad94] res_rtp_asterisk.c: [Oct  1 07:54:36]        > 0x7f8030014880 -- Strict RTP learning after remote address set to: 169.132.xxx.xxx:20896
[Oct  1 07:54:36] VERBOSE[7553][C-0009ad94] app_dial.c: [Oct  1 07:54:36]     -- SIP/Carrier-00069619 is making progress passing it to Local/91909xxxxxxx@default-000821c6;2
[Oct  1 07:54:36] VERBOSE[7553][C-0009ad94] res_rtp_asterisk.c: [Oct  1 07:54:36]        > 0x7f8030014880 -- Strict RTP switching to RTP remote address 169.132.xxx.xxx:20896 as source
[Oct  1 07:54:38] VERBOSE[7553][C-0009ad94] res_rtp_asterisk.c: [Oct  1 07:54:38]        > 0x7f8030014880 -- Strict RTP learning complete - Locking on source address 169.132.xxx.xxx:20896
[Oct  1 07:54:38] VERBOSE[7553][C-0009ad94] app_dial.c: [Oct  1 07:54:38]     -- SIP/Carrier-00069619 answered Local/91909xxxxxxx@default-000821c6;2
[Oct  1 07:54:38] VERBOSE[7552][C-0009ad94] pbx.c: [Oct  1 07:54:38]        > Channel Local/91909xxxxxxx@default-000821c6;1 was answered
[Oct  1 07:54:38] VERBOSE[7563][C-0009ad94] pbx.c: [Oct  1 07:54:38]     -- Executing [8366@default:1] Playback("SIP/Carrier-00069619", "sip-silence") in new stack
[Oct  1 07:54:38] VERBOSE[7553][C-0009ad94] pbx.c: [Oct  1 07:54:38]     -- Executing [h@default:1] AGI("Local/91909xxxxxxx@default-000821c6;2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----6-----0") in new stack
[Oct  1 07:54:38] VERBOSE[7563][C-0009ad94] file.c: [Oct  1 07:54:38]     -- <SIP/Carrier-00069619> Playing 'sip-silence.gsm' (language 'en')
[Oct  1 07:54:38] VERBOSE[7563][C-0009ad94] pbx.c: [Oct  1 07:54:38]     -- Executing [8366@default:2] AGI("SIP/Carrier-00069619", "agi://127.0.0.1:4577/call_log") in new stack
[Oct  1 07:54:38] VERBOSE[7563][C-0009ad94] res_agi.c: [Oct  1 07:54:38]     -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=HS_IVR_2))
[Oct  1 07:54:38] VERBOSE[7563][C-0009ad94] res_agi.c: [Oct  1 07:54:38]     -- <SIP/Carrier-00069619>AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Oct  1 07:54:38] VERBOSE[7563][C-0009ad94] pbx.c: [Oct  1 07:54:38]     -- Executing [8366@default:3] AGI("SIP/Carrier-00069619", "agi-VDAD_ALL_outbound.agi,SURVEYCAMP-----LB") in new stack
[Oct  1 07:54:38] VERBOSE[7563][C-0009ad94] res_agi.c: [Oct  1 07:54:38]     -- Launched AGI Script /usr/share/asterisk/agi-bin/agi-VDAD_ALL_outbound.agi
[Oct  1 07:54:39] VERBOSE[7563][C-0009ad94] res_agi.c: [Oct  1 07:54:39]     -- AGI Script Executing Application: (Monitor) Options: (wav,/var/spool/asterisk/monitor/MIX/20181001-075439_909xxxxxxx)
[Oct  1 07:54:39] VERBOSE[7563][C-0009ad94] res_agi.c: [Oct  1 07:54:39]     -- <SIP/Carrier-00069619> Playing 'sip-silence.gsm' (escape_digits=) (sample_offset 0) (language 'en')
[Oct  1 07:54:39] VERBOSE[7563][C-0009ad94] res_agi.c: [Oct  1 07:54:39]     -- <SIP/Carrier-00069619> Playing 'OUT-8khz.slin' (escape_digits=123) (sample_offset 0) (language 'en')
[Oct  1 07:54:39] VERBOSE[7553][C-0009ad94] res_agi.c: [Oct  1 07:54:39]     -- <Local/91909xxxxxxx@default-000821c6;2>AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----6-----0 completed, returning 0
[Oct  1 07:54:39] VERBOSE[7553][C-0009ad94] pbx.c: [Oct  1 07:54:39]   == Spawn extension (default, 91909xxxxxxx, 2) exited non-zero on 'Local/91909xxxxxxx@default-000821c6;2'
[Oct  1 07:54:43] DTMF[7563][C-0009ad94] channel.c: DTMF begin '1' received on SIP/Carrier-00069619
[Oct  1 07:54:43] DTMF[7563][C-0009ad94] channel.c: DTMF begin ignored '1' on SIP/Carrier-00069619
[Oct  1 07:54:44] DTMF[7563][C-0009ad94] channel.c: DTMF end '1' received on SIP/Carrier-00069619, duration 220 ms
[Oct  1 07:54:44] DTMF[7563][C-0009ad94] channel.c: DTMF end passthrough '1' on SIP/Carrier-00069619
[Oct  1 07:54:44] VERBOSE[7563][C-0009ad94] res_agi.c: [Oct  1 07:54:44]     -- <SIP/Carrier-00069619>AGI Script agi-VDAD_ALL_outbound.agi completed, returning 0
[Oct  1 07:54:44] VERBOSE[7563][C-0009ad94] pbx.c: [Oct  1 07:54:44]     -- Executing [144*076*003*247*8600051@default:1] Goto("SIP/Carrier-00069619", "default,8600051,1") in new stack
[Oct  1 07:54:44] VERBOSE[7563][C-0009ad94] pbx.c: [Oct  1 07:54:44]     -- Goto (default,8600051,1)
[Oct  1 07:54:44] VERBOSE[7563][C-0009ad94] pbx.c: [Oct  1 07:54:44]     -- Executing [8600051@default:1] MeetMe("SIP/Carrier-00069619", "8600051,F") in new stack
[Oct  1 07:54:51] VERBOSE[7563][C-0009ad94] pbx.c: [Oct  1 07:54:51]   == Spawn extension (default, 8600051, 1) exited non-zero on 'SIP/Carrier-00069619'
[Oct  1 07:54:51] VERBOSE[7563][C-0009ad94] pbx.c: [Oct  1 07:54:51]     -- Executing [h@default:1] AGI("SIP/Carrier-00069619", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0---------------") in new stack
[Oct  1 07:54:51] VERBOSE[7563][C-0009ad94] res_agi.c: [Oct  1 07:54:51]     -- <SIP/Carrier-00069619>AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0--------------- completed, returning 0
mubeen
 
Posts: 72
Joined: Mon Feb 19, 2018 1:49 pm

Re: Survey campaign call gets hangup after getting DTMF

Postby mubeen » Tue Oct 02, 2018 12:27 pm

Tried almost every possibility, not sure if this works, thinking of writing my own dial plan and agi for this....
mubeen
 
Posts: 72
Joined: Mon Feb 19, 2018 1:49 pm

Re: Survey campaign call gets hangup after getting DTMF

Postby thephaseusa » Thu Oct 04, 2018 5:44 pm

You mean press 1’s are being dropped instead of transfer to agent? Are you using the new vicibox 8.0.1? Were your survey campaigns working before with vicidial?
thephaseusa
 
Posts: 345
Joined: Tue May 16, 2017 2:23 pm

Re: Survey campaign call gets hangup after getting DTMF

Postby mubeen » Tue Oct 09, 2018 5:44 pm

Yes, I'm using vicibox 8.0.1 and I never tried survey campaing before
mubeen
 
Posts: 72
Joined: Mon Feb 19, 2018 1:49 pm


Return to Support

Who is online

Users browsing this forum: Google [Bot], Majestic-12 [Bot] and 34 guests