Press 1 IVR DTMF issue
Posted: Mon Sep 16, 2019 2:12 am
When i start survey campaign Calls are getting hanged up automatically after passing through DTMF signal without playing Optin MSG.
Here is Asterisk CLI
[Sep 14 23:22:00] -- Called 9812076557173@default
[Sep 14 23:22:00] -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=TEST))
[Sep 14 23:22:00] -- <Local/9812076557173@default-00001919;2>AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Sep 14 23:22:00] -- Executing [9812076557173@default:2] Set("Local/9812076557173@default-00001919;2", "CALLERID(num)=18002752273") in new stack
[Sep 14 23:22:00] -- Executing [9812076557173@default:3] Dial("Local/9812076557173@default-00001919;2", "sip/icon/12076557173,,tTo") in new stack
[Sep 14 23:22:00] ERROR[9732][C-000029ef]: netsock2.c:305 ast_sockaddr_resolve: getaddrinfo("dialerliveagentclub", "(null)", ...): Name or service not known
[Sep 14 23:22:00] WARNING[9732][C-000029ef]: acl.c:835 resolve_first: Unable to lookup 'dialerliveagentclub'
[Sep 14 23:22:00] == Using SIP RTP CoS mark 5
[Sep 14 23:22:00] -- Called sip/icon/12076557173
[Sep 14 23:22:00] > 0x7f81e401fcc0 -- Strict RTP learning after remote address set to: 209.165.1.154:54772
[Sep 14 23:22:00] -- SIP/icon-0000192a is making progress passing it to Local/9812014781113@default-00001916;2
[Sep 14 23:22:00] -- Local/9812014781113@default-00001916;1 is making progress
[Sep 14 23:22:00] DTMF[9493][C-000029b9]: channel.c:4124 __ast_read: DTMF begin '1' received on SIP/icon-0000190d
[Sep 14 23:22:00] DTMF[9493][C-000029b9]: channel.c:4128 __ast_read: DTMF begin ignored '1' on SIP/icon-0000190d
[Sep 14 23:22:00] DTMF[9493][C-000029b9]: channel.c:4038 __ast_read: DTMF end '1' received on SIP/icon-0000190d, duration 70 ms
[Sep 14 23:22:00] DTMF[9493][C-000029b9]: channel.c:4108 __ast_read: DTMF end passthrough '1' on SIP/icon-0000190d
[Sep 14 23:22:00] -- <SIP/icon-0000190d>AGI Script agi-VDAD_ALL_outbound.agi completed, returning 0
[Sep 14 23:22:00] -- Executing [8366@default:3] Hangup("SIP/icon-0000190d", "") in new stack
[Sep 14 23:22:00] == Spawn extension (default, 8366, 3) exited non-zero on 'SIP/icon-0000190d'
[Sep 14 23:22:00] -- Executing [h@default:1] AGI("SIP/icon-0000190d", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------------SIP 200 OK)") in new stack
[Sep 14 23:22:00] -- <SIP/icon-0000190d>AGI Script agi://127.0.0.1:4577/call_log--HVcauses ... -------SIP 200 OK) completed, returning 0
[Sep 14 23:22:01] -- <SIP/icon-00001900>AGI Script agi-VDAD_ALL_outbound.agi completed, returning 0
During my testing it works without any flaws with the same carrier. Here is CLI
[Sep 16 01:46:04] == Manager 'sendcron' logged on from 127.0.0.1
[Sep 16 01:46:04] -- Channel SIP/icon-00002b57 left 'simple_bridge' basic-bridge <c6bfaca0-743b-418e-bae1-218192525d8d>
[Sep 16 01:46:04] -- Executing [8366@default:1] AGI("SIP/icon-00002b57", "agi://127.0.0.1:4577/call_log") in new stack
[Sep 16 01:46:04] -- Channel Local/9818052508556@default-00002b96;2 left 'simple_bridge' basic-bridge <c6bfaca0-743b-418e-bae1-218192525d8d>
[Sep 16 01:46:04] == Spawn extension (default, 9818052508556, 3) exited non-zero on 'Local/9818052508556@default-00002b96;2'
[Sep 16 01:46:04] -- Executing [h@default:1] AGI("Local/9818052508556@default-00002b96;2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----7-----0-----SIP 200 OK)") in new stack
[Sep 16 01:46:04] -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=TESTIVR))
[Sep 16 01:46:04] -- <SIP/icon-00002b57>AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Sep 16 01:46:04] -- Executing [8366@default:2] AGI("SIP/icon-00002b57", "agi-VDAD_ALL_outbound.agi,SURVEYCAMP-----LB-----V9160145570000001474") in new stack
[Sep 16 01:46:04] -- Launched AGI Script /usr/share/asterisk/agi-bin/agi-VDAD_ALL_outbound.agi
[Sep 16 01:46:04] -- AGI Script Executing Application: (Monitor) Options: (wav,/var/spool/asterisk/monitor/MIX/20190916-014604_8052508556)
[Sep 16 01:46:04] -- <SIP/icon-00002b57> Playing 'sip-silence.gsm' (escape_digits=) (sample_offset 0) (language 'en')
[Sep 16 01:46:04] > 0x7f81d0103ed0 -- Strict RTP switching to RTP target address 209.165.1.154:41924 as source
[Sep 16 01:46:04] -- <SIP/icon-00002b57> Playing 'unauthorized_wav.slin' (escape_digits=1238) (sample_offset 0) (language 'en')
[Sep 16 01:46:04] > 0x7f81d0103ed0 -- Strict RTP learning complete - Locking on source address 209.165.1.154:41924
[Sep 16 01:46:05] == Manager 'sendcron' logged off from 127.0.0.1
[Sep 16 01:46:05] == Manager 'sendcron' logged off from 127.0.0.1
[Sep 16 01:46:05] -- <Local/9818052508556@default-00002b96;2>AGI Script agi://127.0.0.1:4577/call_log--HVcauses ... -0-----SIP 200 OK) completed, returning 0
[Sep 16 01:46:05] == Spawn extension (default, 138366, 2) exited non-zero on 'Local/9818052508556@default-00002b96;1'
[Sep 16 01:46:05] WARNING[25095][C-000045d9]: func_hangupcause.c:140 hangupcause_read: Unable to find information for channel
[Sep 16 01:46:05] -- Executing [h@default:1] AGI("Local/9818052508556@default-00002b96;1", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------------)") in new stack
[Sep 16 01:46:06] -- <Local/9818052508556@default-00002b96;1>AGI Script agi://127.0.0.1:4577/call_log--HVcauses ... ----------) completed, returning 0
[Sep 16 01:46:06] == Manager 'sendcron' logged on from 127.0.0.1
[Sep 16 01:46:06] == Manager 'sendcron' logged off from 127.0.0.1
[Sep 16 01:46:20] DTMF[25149][C-000045d8]: channel.c:4124 __ast_read: DTMF begin '1' received on SIP/icon-00002b57
[Sep 16 01:46:20] DTMF[25149][C-000045d8]: channel.c:4128 __ast_read: DTMF begin ignored '1' on SIP/icon-00002b57
[Sep 16 01:46:20] DTMF[25149][C-000045d8]: channel.c:4038 __ast_read: DTMF end '1' received on SIP/icon-00002b57, duration 175 ms
[Sep 16 01:46:20] DTMF[25149][C-000045d8]: channel.c:4108 __ast_read: DTMF end passthrough '1' on SIP/icon-00002b57
[Sep 16 01:46:20] -- <SIP/icon-00002b57> Playing 'optin_thankyou_gsm.gsm' (escape_digits=) (sample_offset 0) (language 'en')
[Sep 16 01:46:26] -- <SIP/icon-00002b57>AGI Script agi-VDAD_ALL_outbound.agi completed, returning 0
[Sep 16 01:46:26] -- Executing [173*199*122*093*8600051@default:1] Goto("SIP/icon-00002b57", "default,8600051,1") in new stack
[Sep 16 01:46:26] -- Goto (default,8600051,1)
[Sep 16 01:46:26] -- Executing [8600051@default:1] MeetMe("SIP/icon-00002b57", "8600051,F") in new stack
Can any one help me to figure out the problem?
Here is Asterisk CLI
[Sep 14 23:22:00] -- Called 9812076557173@default
[Sep 14 23:22:00] -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=TEST))
[Sep 14 23:22:00] -- <Local/9812076557173@default-00001919;2>AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Sep 14 23:22:00] -- Executing [9812076557173@default:2] Set("Local/9812076557173@default-00001919;2", "CALLERID(num)=18002752273") in new stack
[Sep 14 23:22:00] -- Executing [9812076557173@default:3] Dial("Local/9812076557173@default-00001919;2", "sip/icon/12076557173,,tTo") in new stack
[Sep 14 23:22:00] ERROR[9732][C-000029ef]: netsock2.c:305 ast_sockaddr_resolve: getaddrinfo("dialerliveagentclub", "(null)", ...): Name or service not known
[Sep 14 23:22:00] WARNING[9732][C-000029ef]: acl.c:835 resolve_first: Unable to lookup 'dialerliveagentclub'
[Sep 14 23:22:00] == Using SIP RTP CoS mark 5
[Sep 14 23:22:00] -- Called sip/icon/12076557173
[Sep 14 23:22:00] > 0x7f81e401fcc0 -- Strict RTP learning after remote address set to: 209.165.1.154:54772
[Sep 14 23:22:00] -- SIP/icon-0000192a is making progress passing it to Local/9812014781113@default-00001916;2
[Sep 14 23:22:00] -- Local/9812014781113@default-00001916;1 is making progress
[Sep 14 23:22:00] DTMF[9493][C-000029b9]: channel.c:4124 __ast_read: DTMF begin '1' received on SIP/icon-0000190d
[Sep 14 23:22:00] DTMF[9493][C-000029b9]: channel.c:4128 __ast_read: DTMF begin ignored '1' on SIP/icon-0000190d
[Sep 14 23:22:00] DTMF[9493][C-000029b9]: channel.c:4038 __ast_read: DTMF end '1' received on SIP/icon-0000190d, duration 70 ms
[Sep 14 23:22:00] DTMF[9493][C-000029b9]: channel.c:4108 __ast_read: DTMF end passthrough '1' on SIP/icon-0000190d
[Sep 14 23:22:00] -- <SIP/icon-0000190d>AGI Script agi-VDAD_ALL_outbound.agi completed, returning 0
[Sep 14 23:22:00] -- Executing [8366@default:3] Hangup("SIP/icon-0000190d", "") in new stack
[Sep 14 23:22:00] == Spawn extension (default, 8366, 3) exited non-zero on 'SIP/icon-0000190d'
[Sep 14 23:22:00] -- Executing [h@default:1] AGI("SIP/icon-0000190d", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------------SIP 200 OK)") in new stack
[Sep 14 23:22:00] -- <SIP/icon-0000190d>AGI Script agi://127.0.0.1:4577/call_log--HVcauses ... -------SIP 200 OK) completed, returning 0
[Sep 14 23:22:01] -- <SIP/icon-00001900>AGI Script agi-VDAD_ALL_outbound.agi completed, returning 0
During my testing it works without any flaws with the same carrier. Here is CLI
[Sep 16 01:46:04] == Manager 'sendcron' logged on from 127.0.0.1
[Sep 16 01:46:04] -- Channel SIP/icon-00002b57 left 'simple_bridge' basic-bridge <c6bfaca0-743b-418e-bae1-218192525d8d>
[Sep 16 01:46:04] -- Executing [8366@default:1] AGI("SIP/icon-00002b57", "agi://127.0.0.1:4577/call_log") in new stack
[Sep 16 01:46:04] -- Channel Local/9818052508556@default-00002b96;2 left 'simple_bridge' basic-bridge <c6bfaca0-743b-418e-bae1-218192525d8d>
[Sep 16 01:46:04] == Spawn extension (default, 9818052508556, 3) exited non-zero on 'Local/9818052508556@default-00002b96;2'
[Sep 16 01:46:04] -- Executing [h@default:1] AGI("Local/9818052508556@default-00002b96;2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----7-----0-----SIP 200 OK)") in new stack
[Sep 16 01:46:04] -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=TESTIVR))
[Sep 16 01:46:04] -- <SIP/icon-00002b57>AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Sep 16 01:46:04] -- Executing [8366@default:2] AGI("SIP/icon-00002b57", "agi-VDAD_ALL_outbound.agi,SURVEYCAMP-----LB-----V9160145570000001474") in new stack
[Sep 16 01:46:04] -- Launched AGI Script /usr/share/asterisk/agi-bin/agi-VDAD_ALL_outbound.agi
[Sep 16 01:46:04] -- AGI Script Executing Application: (Monitor) Options: (wav,/var/spool/asterisk/monitor/MIX/20190916-014604_8052508556)
[Sep 16 01:46:04] -- <SIP/icon-00002b57> Playing 'sip-silence.gsm' (escape_digits=) (sample_offset 0) (language 'en')
[Sep 16 01:46:04] > 0x7f81d0103ed0 -- Strict RTP switching to RTP target address 209.165.1.154:41924 as source
[Sep 16 01:46:04] -- <SIP/icon-00002b57> Playing 'unauthorized_wav.slin' (escape_digits=1238) (sample_offset 0) (language 'en')
[Sep 16 01:46:04] > 0x7f81d0103ed0 -- Strict RTP learning complete - Locking on source address 209.165.1.154:41924
[Sep 16 01:46:05] == Manager 'sendcron' logged off from 127.0.0.1
[Sep 16 01:46:05] == Manager 'sendcron' logged off from 127.0.0.1
[Sep 16 01:46:05] -- <Local/9818052508556@default-00002b96;2>AGI Script agi://127.0.0.1:4577/call_log--HVcauses ... -0-----SIP 200 OK) completed, returning 0
[Sep 16 01:46:05] == Spawn extension (default, 138366, 2) exited non-zero on 'Local/9818052508556@default-00002b96;1'
[Sep 16 01:46:05] WARNING[25095][C-000045d9]: func_hangupcause.c:140 hangupcause_read: Unable to find information for channel
[Sep 16 01:46:05] -- Executing [h@default:1] AGI("Local/9818052508556@default-00002b96;1", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------------)") in new stack
[Sep 16 01:46:06] -- <Local/9818052508556@default-00002b96;1>AGI Script agi://127.0.0.1:4577/call_log--HVcauses ... ----------) completed, returning 0
[Sep 16 01:46:06] == Manager 'sendcron' logged on from 127.0.0.1
[Sep 16 01:46:06] == Manager 'sendcron' logged off from 127.0.0.1
[Sep 16 01:46:20] DTMF[25149][C-000045d8]: channel.c:4124 __ast_read: DTMF begin '1' received on SIP/icon-00002b57
[Sep 16 01:46:20] DTMF[25149][C-000045d8]: channel.c:4128 __ast_read: DTMF begin ignored '1' on SIP/icon-00002b57
[Sep 16 01:46:20] DTMF[25149][C-000045d8]: channel.c:4038 __ast_read: DTMF end '1' received on SIP/icon-00002b57, duration 175 ms
[Sep 16 01:46:20] DTMF[25149][C-000045d8]: channel.c:4108 __ast_read: DTMF end passthrough '1' on SIP/icon-00002b57
[Sep 16 01:46:20] -- <SIP/icon-00002b57> Playing 'optin_thankyou_gsm.gsm' (escape_digits=) (sample_offset 0) (language 'en')
[Sep 16 01:46:26] -- <SIP/icon-00002b57>AGI Script agi-VDAD_ALL_outbound.agi completed, returning 0
[Sep 16 01:46:26] -- Executing [173*199*122*093*8600051@default:1] Goto("SIP/icon-00002b57", "default,8600051,1") in new stack
[Sep 16 01:46:26] -- Goto (default,8600051,1)
[Sep 16 01:46:26] -- Executing [8600051@default:1] MeetMe("SIP/icon-00002b57", "8600051,F") in new stack
Can any one help me to figure out the problem?