Summary:	ASTERISK-13262: [applicationmap] is not detecting for multiple digits
Reporter:	sasikala (sasikala)	Labels:
Date Opened:	2008-12-23 03:48:07.000-0600	Date Closed:	2011-06-07 14:02:47
Priority:	Major	Regression?	No
Status:	Closed/Complete	Components:	Resources/res_features
Versions:		Frequency of Occurrence
Related Issues:
Environment:		Attachments:
Description:	Hi, I have given the following in the features.conf, in the [applicationmap] context. start => 10,caller,Macro,start invite => 12,caller,Macro,macok when am in a call, am pressing 10, number of times, this feature is not starting. but after some retry it is taking effect. Here with I am giving the logs which is appearing in asterisk console. [Dec 23 15:01:57] DTMF[10932]: channel.c:2444 __ast_read: DTMF begin '0' received on SIP/43902782-aed00468 [Dec 23 15:01:57] DTMF[10932]: channel.c:2454 __ast_read: DTMF begin passthrough '0' on SIP/43902782-aed00468 [Dec 23 15:01:57] DTMF[9750]: channel.c:2381 __ast_read: DTMF end '0' received on SIP/43902782-aed00468, duration 100 ms [Dec 23 15:01:57] DTMF[9750]: channel.c:2422 __ast_read: DTMF end accepted with begin '0' on SIP/43902782-aed00468 [Dec 23 15:01:57] DTMF[9750]: channel.c:2438 __ast_read: DTMF end passthrough '0' on SIP/43902782-aed00468 [Dec 23 15:01:57] DTMF[10932]: channel.c:2381 __ast_read: DTMF end '0' received on SIP/43902782-aed00468, duration 100 ms [Dec 23 15:01:57] DTMF[10932]: channel.c:2398 __ast_read: DTMF end '0' put into dtmf queue on SIP/43902782-aed00468 [Dec 23 15:01:58] DTMF[10932]: channel.c:2254 __ast_read: DTMF begin emulation of '0' with duration 100 queued on SIP/43902782-aed00468 [Dec 23 15:01:58] DEBUG[9660]: sched.c:204 sched_settime: Request to schedule in the past?!?! [Dec 23 15:01:58] DTMF[10932]: channel.c:2501 __ast_read: DTMF end emulation of '0' queued on SIP/43902782-aed00468 [Dec 23 15:02:00] DTMF[10932]: channel.c:2444 __ast_read: DTMF begin '1' received on SIP/43902782-aed00468 [Dec 23 15:02:00] DTMF[10932]: channel.c:2454 __ast_read: DTMF begin passthrough '1' on SIP/43902782-aed00468 [Dec 23 15:02:00] DTMF[10932]: channel.c:2381 __ast_read: DTMF end '1' received on SIP/43902782-aed00468, duration 100 ms [Dec 23 15:02:00] DTMF[10932]: channel.c:2422 __ast_read: DTMF end accepted with begin '1' on SIP/43902782-aed00468 [Dec 23 15:02:00] DTMF[10932]: channel.c:2438 __ast_read: DTMF end passthrough '1' on SIP/43902782-aed00468 [Dec 23 15:02:00] DTMF[10932]: channel.c:2444 __ast_read: DTMF begin '0' received on SIP/43902782-aed00468 [Dec 23 15:02:00] DTMF[10932]: channel.c:2454 __ast_read: DTMF begin passthrough '0' on SIP/43902782-aed00468 [Dec 23 15:02:00] DTMF[10932]: channel.c:2381 __ast_read: DTMF end '0' received on SIP/43902782-aed00468, duration 100 ms [Dec 23 15:02:00] DTMF[10932]: channel.c:2422 __ast_read: DTMF end accepted with begin '0' on SIP/43902782-aed00468 [Dec 23 15:02:00] DTMF[10932]: channel.c:2438 __ast_read: DTMF end passthrough '0' on SIP/43902782-aed00468    -- Zap/1-1 is ringing [Dec 23 15:02:03] DTMF[10932]: channel.c:2444 __ast_read: DTMF begin '1' received on SIP/43902782-aed00468      [Dec 23 15:02:03] DTMF[10932]: channel.c:2454 __ast_read: DTMF begin passthrough '1' on SIP/43902782-aed00468 [Dec 23 15:02:03] DTMF[10932]: channel.c:2381 __ast_read: DTMF end '1' received on SIP/43902782-aed00468, duration 100 ms [Dec 23 15:02:03] DTMF[10932]: channel.c:2422 __ast_read: DTMF end accepted with begin '1' on SIP/43902782-aed00 468[Dec 23 15:02:03] DTMF[10932]: channel.c:2438 __ast_read: DTMF end passthrough '1' on SIP/43902782-aed00468 [Dec 23 15:02:04] DTMF[10932]: channel.c:2444 __ast_read: DTMF begin '0' received on SIP/43902782-aed00468 [Dec 23 15:02:04] DTMF[10932]: channel.c:2454 __ast_read: DTMF begin passthrough '0' on SIP/43902782-aed00468 [Dec 23 15:02:04] DTMF[10932]: channel.c:2381 __ast_read: DTMF end '0' received on SIP/43902782-aed00468, duration 100 ms [Dec 23 15:02:04] DTMF[10932]: channel.c:2422 __ast_read: DTMF end accepted with begin '0' on SIP/43902782-aed00468 [Dec 23 15:02:04] DTMF[10932]: channel.c:2438 __ast_read: DTMF end passthrough '0' on SIP/43902782-aed00468 [Dec 23 15:02:05] DTMF[10932]: channel.c:2444 __ast_read: DTMF begin '1' received on SIP/43902782-aed00468 [Dec 23 15:02:05] DTMF[10932]: channel.c:2454 __ast_read: DTMF begin passthrough '1' on SIP/43902782-aed00468 [Dec 23 15:02:05] DTMF[10932]: channel.c:2381 __ast_read: DTMF end '1' received on SIP/43902782-aed00468, duration 100 ms [Dec 23 15:02:05] DTMF[10932]: channel.c:2422 __ast_read: DTMF end accepted with begin '1' on SIP/43902782-aed00468 [Dec 23 15:02:05] DTMF[10932]: channel.c:2438 __ast_read: DTMF end passthrough '1' on SIP/43902782-aed00468 [Dec 23 15:02:05] DTMF[10932]: channel.c:2444 __ast_read: DTMF begin '0' received on SIP/43902782-aed00468 [Dec 23 15:02:05] DTMF[10932]: channel.c:2454 __ast_read: DTMF begin passthrough '0' on SIP/43902782-aed00468 [Dec 23 15:02:06] DTMF[9750]: channel.c:2381 __ast_read: DTMF end '0' received on SIP/43902782-aed00468, duration 100 ms [Dec 23 15:02:06] DTMF[9750]: channel.c:2422 __ast_read: DTMF end accepted with begin '0' on SIP/43902782-aed00468 [Dec 23 15:02:06] DTMF[9750]: channel.c:2438 __ast_read: DTMF end passthrough '0' on SIP/43902782-aed00468 [Dec 23 15:02:06] DTMF[10932]: channel.c:2381 __ast_read: DTMF end '0' received on SIP/43902782-aed00468, duration 100 ms [Dec 23 15:02:06] DTMF[10932]: channel.c:2398 __ast_read: DTMF end '0' put into dtmf queue on SIP/43902782-aed00468 [Dec 23 15:02:06] DTMF[10932]: channel.c:2254 __ast_read: DTMF begin emulation of '0' with duration 100 queued on SIP/43902782-aed00468 [Dec 23 15:02:06] DTMF[10932]: channel.c:2501 __ast_read: DTMF end emulation of '0' queued on SIP/43902782-aed00468 [Dec 23 15:02:06] DEBUG[9663]: chan_zap.c:1420 zt_enable_ec: Echo cancellation already on    -- Zap/1-1 answered SIP/43902745-aed271e8 [Dec 23 15:02:07] DTMF[10932]: channel.c:2444 __ast_read: DTMF begin '1' received on SIP/43902782-aed00468 [Dec 23 15:02:07] DTMF[10932]: channel.c:2438 __ast_read: DTMF end passthrough '1' on SIP/43902782-aed00468 [Dec 23 15:02:08] DTMF[10932]: channel.c:2444 __ast_read: DTMF begin '0' received on SIP/43902782-aed00468 [Dec 23 15:02:08] DTMF[10932]: channel.c:2454 __ast_read: DTMF begin passthrough '0' on SIP/43902782-aed00468 [Dec 23 15:02:08] DTMF[10932]: channel.c:2381 __ast_read: DTMF end '0' received on SIP/43902782-aed00468, duration 100 ms [Dec 23 15:02:08] DTMF[10932]: channel.c:2422 __ast_read: DTMF end accepted with begin '0' on SIP/43902782-aed00468 [Dec 23 15:02:08] DTMF[10932]: channel.c:2438 __ast_read: DTMF end passthrough '0' on SIP/43902782-aed00468 [Dec 23 15:02:09] DTMF[10932]: channel.c:2444 __ast_read: DTMF begin '1' received on SIP/43902782-aed00468 [Dec 23 15:02:09] DTMF[10932]: channel.c:2454 __ast_read: DTMF begin passthrough '1' on SIP/43902782-aed00468 [Dec 23 15:02:09] DTMF[10932]: channel.c:2381 __ast_read: DTMF end '1' received on SIP/43902782-aed00468, duration 100 ms [Dec 23 15:02:09] DTMF[10932]: channel.c:2422 __ast_read: DTMF end accepted with begin '1' on SIP/43902782-aed00468 [Dec 23 15:02:09] DTMF[10932]: channel.c:2438 __ast_read: DTMF end passthrough '1' on SIP/43902782-aed00468 [Dec 23 15:02:10] DTMF[10932]: channel.c:2444 __ast_read: DTMF begin '0' received on SIP/43902782-aed00468 [Dec 23 15:02:10] DTMF[10932]: channel.c:2454 __ast_read: DTMF begin passthrough '0' on SIP/43902782-aed00468 [Dec 23 15:02:10] DTMF[10932]: channel.c:2381 __ast_read: DTMF end '0' received on SIP/43902782-aed00468, duration 100 ms [Dec 23 15:02:10] DTMF[10932]: channel.c:2422 __ast_read: DTMF end accepted with begin '0' on SIP/43902782-aed00468 [Dec 23 15:02:10] DTMF[10932]: channel.c:2438 __ast_read: DTMF end passthrough '0' on SIP/43902782-aed00468 [Dec 23 15:02:11] DTMF[10932]: channel.c:2444 __ast_read: DTMF begin '1' received on SIP/43902782-aed00468 [Dec 23 15:02:11] DTMF[10932]: channel.c:2454 __ast_read: DTMF begin passthrough '1' on SIP/43902782-aed00468 [Dec 23 15:02:11] DTMF[10932]: channel.c:2381 __ast_read: DTMF end '1' received on SIP/43902782-aed00468, duration 100 ms [Dec 23 15:02:11] DTMF[10932]: channel.c:2422 __ast_read: DTMF end accepted with begin '1' on SIP/43902782-aed00468 [Dec 23 15:02:11] DTMF[10932]: channel.c:2438 __ast_read: DTMF end passthrough '1' on SIP/43902782-aed00468 [Dec 23 15:02:11] DTMF[10932]: channel.c:2444 __ast_read: DTMF begin '0' received on SIP/43902782-aed00468 [Dec 23 15:02:11] DTMF[10932]: channel.c:2454 __ast_read: DTMF begin passthrough '0' on SIP/43902782-aed00468 [Dec 23 15:02:11] DTMF[10932]: channel.c:2381 __ast_read: DTMF end '0' received on SIP/43902782-aed00468, duration 100 ms [Dec 23 15:02:11] DTMF[10932]: channel.c:2422 __ast_read: DTMF end accepted with begin '0' on SIP/43902782-aed00468 [Dec 23 15:02:13] DTMF[10932]: channel.c:2444 __ast_read: DTMF begin '1' received on SIP/43902782-aed00468 [Dec 23 15:02:13] DTMF[10932]: channel.c:2454 __ast_read: DTMF begin passthrough '1' on SIP/43902782-aed00468 [Dec 23 15:02:13] DTMF[10932]: channel.c:2381 __ast_read: DTMF end '1' received on SIP/43902782-aed00468, duration 100 ms [Dec 23 15:02:13] DTMF[10932]: channel.c:2422 __ast_read: DTMF end accepted with begin '1' on SIP/43902782-aed00468 [Dec 23 15:02:13] DTMF[10932]: channel.c:2438 __ast_read: DTMF end passthrough '1' on SIP/43902782-aed00468 [Dec 23 15:02:13] DTMF[10932]: channel.c:2444 __ast_read: DTMF begin '0' received on SIP/43902782-aed00468 [Dec 23 15:02:13] DTMF[10932]: channel.c:2454 __ast_read: DTMF begin passthrough '0' on SIP/43902782-aed00468 [Dec 23 15:02:13] DTMF[9750]: channel.c:2381 __ast_read: DTMF end '0' received on SIP/43902782-aed00468, duration 100 ms [Dec 23 15:02:13] DTMF[9750]: channel.c:2422 __ast_read: DTMF end accepted with begin '0' on SIP/43902782-aed00468 [Dec 23 15:02:13] DTMF[9750]: channel.c:2438 __ast_read: DTMF end passthrough '0' on SIP/43902782-aed00468 [Dec 23 15:02:13] DTMF[10932]: channel.c:2381 __ast_read: DTMF end '0' received on SIP/43902782-aed00468, duration 100 ms [Dec 23 15:02:13] DTMF[10932]: channel.c:2417 __ast_read: DTMF begin emulation of '0' with duration 100 queued on SIP/43902782-aed00468 [Dec 23 15:02:13] DTMF[10932]: channel.c:2501 __ast_read: DTMF end emulation of '0' queued on SIP/43902782-aed00468 [Dec 23 15:02:16] DTMF[10932]: channel.c:2444 __ast_read: DTMF begin '1' received on SIP/43902782-aed00468 [Dec 23 15:02:16] DTMF[10932]: channel.c:2454 __ast_read: DTMF begin passthrough '1' on SIP/43902782-aed00468 [Dec 23 15:02:16] DTMF[10932]: channel.c:2381 __ast_read: DTMF end '1' received on SIP/43902782-aed00468, duration 100 ms [Dec 23 15:02:16] DTMF[10932]: channel.c:2422 __ast_read: DTMF end accepted with begin '1' on SIP/43902782-aed00468 [Dec 23 15:02:16] DTMF[10932]: channel.c:2438 __ast_read: DTMF end passthrough '1' on SIP/43902782-aed00468 [Dec 23 15:02:16] DTMF[10932]: channel.c:2444 __ast_read: DTMF begin '0' received on SIP/43902782-aed00468 [Dec 23 15:02:16] DTMF[10932]: channel.c:2454 __ast_read: DTMF begin passthrough '0' on SIP/43902782-aed00468 [Dec 23 15:02:16] DTMF[10932]: channel.c:2381 __ast_read: DTMF end '0' received on SIP/43902782-aed00468, duration 100 ms [Dec 23 15:02:16] DTMF[10932]: channel.c:2422 __ast_read: DTMF end accepted with begin '0' on SIP/43902782-aed00468 [Dec 23 15:02:16] DTMF[10932]: channel.c:2438 __ast_read: DTMF end passthrough '0' on SIP/43902782-aed00468    --  Feature Found: start exten: start    -- Executing [s@macro-start:1] AGI("SIP/43902782-aed00468", "/var/lib/asterisk/agi-bin/random.pl") in new stack From the above you could be able to see that, asterisk detected 10 number of times, but at last Feature Found  start exten: start . it said. - If am giving start => *,caller,Macro,start, it is working perfectly. What could be the problem? Is there any limitation, should i have to give only a single digit in the application map? Kindly advice to find the root of the problem and to solve it. regards sasikala
Comments:	By: Mark Michelson (mmichelson) 2008-12-23 12:35:57.000-0600 I suspect that this is a configuration problem. I would be willing to bet that since this is working for you with a single digit, but not with multiple digits, the problem is the featuredigittimeout in features.conf. By default, this is set to 500 ms, meaning that only 500 ms is allowed between DTMF presses when trying to activate a feature. Try setting featuredigittimeout to something higher, like 2000. Your problem will probably go away. By: sasikala (sasikala) 2008-12-24 00:11:15.000-0600 Yes, this is a configuration problem. I have given featuredigittimeout to 2000, now it is working perfectly for even two digits also. thank you so much for your prompt action. You can take this issue as closed. --Sasikala.