[asterisk-bugs] [Asterisk 0016113]: Auto-fallthrough when attempting to enter DTMF using Background() in a Macro()

Asterisk Bug Tracker noreply at bugs.digium.com
Wed Dec 16 23:18:20 CST 2009


A NOTE has been added to this issue. 
====================================================================== 
https://issues.asterisk.org/view.php?id=16113 
====================================================================== 
Reported By:                OrNix
Assigned To:                tilghman
====================================================================== 
Project:                    Asterisk
Issue ID:                   16113
Category:                   General
Reproducibility:            always
Severity:                   minor
Priority:                   normal
Status:                     feedback
Target Version:             1.6.1.13
Asterisk Version:           SVN 
JIRA:                       SWP-440 
Regression:                 Yes 
Reviewboard Link:            
SVN Branch (only for SVN checkouts, not tarball releases): N/A 
SVN Revision (number only!):  
Request Review:              
====================================================================== 
Date Submitted:             2009-10-22 03:59 CDT
Last Modified:              2009-12-16 23:18 CST
====================================================================== 
Summary:                    Auto-fallthrough when attempting to enter DTMF using
Background() in a Macro()
Description: 
Hello, All.

Asterisk calls me using a call-file, then i make call with the following
macro:

[macro-internalcall-with-redirect-callback]
exten => s,1,Dial(SIP/${ARG1},20,jgtedH)
exten => s,n,Goto(s-${DIALSTATUS},1)
exten => s-BUSY,1,Background(busy-cycl0)
exten => s-BUSY,2,Goto(${CONTEXT},s,1)

In ${MACRO_CONTEXT} i have extensions:
exten => *,1,ResetCDR(w)
exten => *,n,Goto(${CONTEXT},s,5) 

I make a call to _XXX, recieve "BUSY". While busy-cycl0 is playing, i hit
the "*"-key.

1.6.1.7rc2 - Strange behavior:
[Oct 16 18:03:19] -- Executing
[s at macro-internalcall-with-redirect-callback:3] Goto("H323/sibirtelecom-1",
"s-BUSY,1") in new stack
[Oct 16 18:03:19] -- Goto
(macro-internalcall-with-redirect-callback,s-BUSY,1)
[Oct 16 18:03:19] -- Executing
[s-BUSY at macro-internalcall-with-redirect-callback:1]
BackGround("H323/sibirtelecom-1", "busy-cycl0") in new stack
[Oct 16 18:03:19] -- <H323/sibirtelecom-1> Playing 'busy-cycl0.alaw'
(language 'en')
[Oct 16 18:03:20] -- Auto fallthrough, channel 'H323/sibirtelecom-1'
status is 'BUSY'
[Oct 16 18:03:24] DEBUG[9435]: pbx_spool.c:405 scan_service: Delaying
retry since we're currently running
'/var/spool/asterisk/outgoing/32631.call'
[Oct 16 18:03:27] NOTICE[9475]: pbx_spool.c:348 attempt_thread: Call
completed to Local/2487305 at qqq


1.6.1.4 - Normal behavior:
[Oct 16 18:06:43] -- Executing
[s at macro-internalcall-with-redirect-callback:3] Goto("H323/sibirtelecom-1",
"s-BUSY,1") in new stack
[Oct 16 18:06:43] -- Goto
(macro-internalcall-with-redirect-callback,s-BUSY,1)
[Oct 16 18:06:43] -- Executing
[s-BUSY at macro-internalcall-with-redirect-callback:1]
BackGround("H323/sibirtelecom-1", "busy-cycl0") in new stack
[Oct 16 18:06:43] -- <H323/sibirtelecom-1> Playing 'busy-cycl0.alaw'
(language 'en')
[Oct 16 18:06:44] == CDR updated on H323/sibirtelecom-1
[Oct 16 18:06:44] -- Executing [*@callback:1]
ResetCDR("H323/sibirtelecom-1", "w") in new stack
[Oct 16 18:06:44] -- Executing [*@callback:2] Goto("H323/sibirtelecom-1",
"callback,s,5") in new stack
[Oct 16 18:06:44] -- Goto (callback,s,5)
[Oct 16 18:06:44] -- Executing [s at callback:5]
WaitExten("H323/sibirtelecom-1", "1") in new stack

This error occurs both with chan_h323 and with chan_ooh323.

P.S. I don't know, what category should i select in this case, please move
 the topic to the correct one.
====================================================================== 

---------------------------------------------------------------------- 
 (0115359) tilghman (administrator) - 2009-12-16 23:18
 https://issues.asterisk.org/view.php?id=16113#c115359 
---------------------------------------------------------------------- 
Still completely unable to reproduce this:

   -- Attempting call on Local/103 at qqq for s at callback:1 (Retry 1)
    -- Executing [103 at qqq:1] Macro("Local/103 at qqq-0ac6;2", "cb-sip,103")
in new stack
    -- Executing [s at macro-cb-sip:1] NoCDR("Local/103 at qqq-0ac6;2", "") in
new stack
    -- Executing [s at macro-cb-sip:2] Set("Local/103 at qqq-0ac6;2",
"CALLERID(num)=3833540661") in new stack
    -- Executing [s at macro-cb-sip:3] Dial("Local/103 at qqq-0ac6;2",
"SIP/103,,Tg") in new stack
  == Using SIP RTP CoS mark 5
  == Using SIP VRTP CoS mark 6
    -- Called 103
    -- SIP/103-00000001 is ringing
    -- SIP/103-00000001 answered Local/103 at qqq-0ac6;2
       > Channel Local/103 at qqq-0ac6;1 was answered.
    -- Executing [s at callback:1] Answer("Local/103 at qqq-0ac6;1", "") in new
stack
    -- Executing [s at callback:2] Set("Local/103 at qqq-0ac6;1",
"CLID=2487305") in new stack
    -- Executing [s at callback:3] Set("Local/103 at qqq-0ac6;1",
"CALLERID(name)=2487305") in new stack
    -- Executing [s at callback:4] Set("Local/103 at qqq-0ac6;1",
"TIMEOUT(digit)=6") in new stack
    -- Digit timeout set to 6.000
    -- Executing [s at callback:5] WaitExten("Local/103 at qqq-0ac6;1", "1") in
new stack
[Dec 16 23:14:57] WARNING[1444]: channel.c:2445 ast_waitfordigit_full:
Unexpected control subclass '-1'
  == Spawn extension (macro-cb-sip, s, 3) exited non-zero on
'Local/103 at qqq-0ac6;2' in macro 'cb-sip'
  == Spawn extension (qqq, 103, 1) exited non-zero on
'Local/103 at qqq-0ac6;2'
    -- Timeout on SIP/103-00000001, continuing...
    -- Executing [s at callback:6] BackGround("SIP/103-00000001",
"tt-weasels") in new stack
    -- <SIP/103-00000001> Playing 'tt-weasels.gsm' (language 'en')
    -- Executing [s at callback:7] WaitExten("SIP/103-00000001", "30") in new
stack
  == CDR updated on SIP/103-00000001
    -- Executing [115 at callback:1] Macro("SIP/103-00000001",
"internalcall-with-redirect-callback,6039,2487305") in new stack
    -- Executing [s at macro-internalcall-with-redirect-callback:1]
Dial("SIP/103-00000001", "SIP/6039,20,jgtedH") in new stack
  == Using SIP RTP CoS mark 5
  == Using SIP VRTP CoS mark 6
[Dec 16 23:15:04] WARNING[1444]: chan_sip.c:4943 create_addr: No such
host: 6039
[Dec 16 23:15:04] WARNING[1444]: app_dial.c:1547 dial_exec_full: Unable to
create channel of type 'SIP' (cause 20 - Unknown)
  == Everyone is busy/congested at this time (1:0/0/1)
    -- Executing [s at macro-internalcall-with-redirect-callback:2]
Goto("SIP/103-00000001", "s-BUSY,1") in new stack
    -- Goto (macro-internalcall-with-redirect-callback,s-BUSY,1)
    -- Executing [s-BUSY at macro-internalcall-with-redirect-callback:1]
BackGround("SIP/103-00000001", "tt-weasels") in new stack
    -- <SIP/103-00000001> Playing 'tt-weasels.gsm' (language 'en')
    -- Executing [s-BUSY at macro-internalcall-with-redirect-callback:2]
Goto("SIP/103-00000001", "macro-internalcall-with-redirect-callback,s,1")
in new stack
    -- Goto (macro-internalcall-with-redirect-callback,s,1)
    -- Executing [s at macro-internalcall-with-redirect-callback:1]
Dial("SIP/103-00000001", "SIP/6039,20,jgtedH") in new stack
  == Using SIP RTP CoS mark 5
  == Using SIP VRTP CoS mark 6
[Dec 16 23:15:07] WARNING[1444]: chan_sip.c:4943 create_addr: No such
host: 6039
[Dec 16 23:15:07] WARNING[1444]: app_dial.c:1547 dial_exec_full: Unable to
create channel of type 'SIP' (cause 20 - Unknown)
  == Everyone is busy/congested at this time (1:0/0/1)
    -- Executing [s at macro-internalcall-with-redirect-callback:2]
Goto("SIP/103-00000001", "s-BUSY,1") in new stack
    -- Goto (macro-internalcall-with-redirect-callback,s-BUSY,1)
    -- Executing [s-BUSY at macro-internalcall-with-redirect-callback:1]
BackGround("SIP/103-00000001", "tt-weasels") in new stack
    -- <SIP/103-00000001> Playing 'tt-weasels.gsm' (language 'en')
...
[Dec 16 23:15:17] NOTICE[1444]: pbx_spool.c:348 attempt_thread: Call
completed to Local/103 at qqq
core show version
Asterisk SVN-branch-1.6.1-r233730 built by tilghman @
gadolinium.jeffandtilghman.foo on a i686 running Linux on 2009-06-01
15:40:13 UTC 

Issue History 
Date Modified    Username       Field                    Change               
====================================================================== 
2009-12-16 23:18 tilghman       Note Added: 0115359                          
======================================================================




More information about the asterisk-bugs mailing list