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