Ticket #1651 (closed Bugs: fixed)

Opened 4 years ago

Last modified 4 years ago

Dial option form ivr

Reported by: mbrevda Assigned to:
Priority: major Milestone: 2.2
Component: IVR Version: 2.2
Keywords: dial Cc:
Confirmation: SVN Revision (if applicable):
Backend Engine: All Backend Engine Version:

Description

see here: http://www.trixbox.org/modules/newbb/viewtopic.php?topic_id=8149&forum=2

I'm having the same issue. With trixbox 1.2.3 with freePBX 2.2 rc3. i have an announcment saying "press 9 to leave a voicemail or hold on the line for an agent" When i press 9 the call is hung up.



The same for me with FrePBX rc3

If someone press 0 to leave a message into VM gets busy

Jan 1 12:40:55 VERBOSE[3582] logger.c: -- Executing GotoIf?("SIP/101-085ff4c8", 

Jan 1 12:40:55 VERBOSE[3582] logger.c: -- Executing GotoIf("SIP/101-085ff4c8", "0?s-DIRECTDIAL|1") in new stack
Jan 1 12:40:55 DEBUG[3582] pbx.c: Not taking any branch
Jan 1 12:40:55 WARNING[3582] func_db.c: DB requires an argument, DB(/)
Jan 1 12:40:55 DEBUG[3582] pbx.c: Function result is ''
Jan 1 12:40:55 VERBOSE[3582] logger.c: -- Executing NoOp("SIP/101-085ff4c8", "CAME FROM: 151 - Blocking VM cause of key: ") in new stack

Change History

01/07/07 11:18:32 changed by naftali5

don't know exactly what caused it, but obviously the BLKVM_OVERRIDE variable is empty. ring groups check for this 

GotoIf($["foo${BLKVM_OVERRIDE}" = "foo"]?skipdb)

but 

[macro-vm]

does not.

01/08/07 08:48:11 changed by mbrevda

what is the BLKVM_OVERRIDE key anyway? also its really not empty: 

/BLKVM/1/SIP/202-1865                             : TRUE
/BLKVM/1/SIP/202-3162                             : TRUE
/BLKVM/1/SIP/202-f4ba                             : TRUE
/BLKVM/1/Zap/6-1                                  : TRUE
/BLKVM/2/Local/201@from-internal-0425,2           : TRUE
/BLKVM/2/Local/201@from-internal-a7fb,2           : TRUE
/BLKVM/2/Local/201@from-internal-d776,2           : TRUE
/BLKVM/2/Local/202@from-internal-57f3,2           : TRUE
/BLKVM/2/SIP/202-c53d                             : TRUE
/BLKVM/2/SIP/202-cde3                             : TRUE
/BLKVM/2/SIP/202-d5d3                             : TRUE
/BLKVM/2/Zap/4-1                                  : TRUE
/BLKVM/301/Local/202@from-internal-2c51,2         : TRUE
/BLKVM/301/Local/202@from-internal-30c1,2         : TRUE
/BLKVM/301/Local/202@from-internal-540e,2         : TRUE
/BLKVM/301/Local/202@from-internal-98e9,2         : TRUE
/BLKVM/301/Local/202@from-internal-aac9,2         : TRUE
/BLKVM/301/Local/202@from-internal-c080,2         : TRUE
/BLKVM/301/Local/202@from-internal-da73,2         : TRUE
/BLKVM/301/Local/202@from-internal-de07,2         : TRUE
/BLKVM/302/Local/201@from-internal-919c,2         : TRUE
/BLKVM/302/Local/201@from-internal-a7f5,2         : TRUE
/BLKVM/302/Zap/6-1                                : TRUE
/BLKVM/303/Local/201@from-internal-31c1,2         : TRUE
/BLKVM/303/Local/201@from-internal-3343,2         : TRUE
/BLKVM/303/Local/201@from-internal-bd20,2         : TRUE
/BLKVM/303/Local/202@from-internal-5f2f,2         : TRUE
/BLKVM/303/Local/202@from-internal-9b93,2         : TRUE
/BLKVM/303/Local/303@from-internal-4cf2,2         : TRUE
/BLKVM/303/Local/303@from-internal-8e57,2         : TRUE
/BLKVM/303/Local/303@from-internal-c13a,2         : TRUE
/BLKVM/303/Local/303@from-internal-e971,2         : TRUE
/BLKVM/303/Local/303@from-internal-f656,2         : TRUE
/BLKVM/303/Local/303@from-internal-fcf8,2         : TRUE
/BLKVM/303/SIP/202-2a7f                           : TRUE
/BLKVM/303/SIP/202-50e8                           : TRUE
/BLKVM/303/SIP/202-649c                           : TRUE
/BLKVM/303/SIP/202-64cf                           : TRUE
/BLKVM/303/SIP/202-b57f                           : TRUE
/BLKVM/303/SIP/202-b680                           : TRUE
/BLKVM/303/SIP/202-e022                           : TRUE
/BLKVM/303/Zap/4-1                                : TRUE
/BLKVM/304/Local/203@from-internal-0ee0,2         : TRUE
/BLKVM/304/Local/203@from-internal-33e5,2         : TRUE
/BLKVM/304/Local/304@from-internal-72c5,2         : TRUE
/BLKVM/304/Local/304@from-internal-95e7,2         : TRUE

well its not the same key, so but then again it doesnt even exist!

01/08/07 09:18:50 changed by naftali5

sorry, that comment was not meant for you, but to try to help narrow it down for the devs, since they generally don't have much time to debug each bug report.

I have actually looked into it a bit more since, and think that what I wrote may be incorrect anyhow.

Please try to attach a bit more of a debug, the lines you posted are hardly enough.

01/08/07 09:33:00 changed by mbrevda 

[root@asterisk1 ~]# asterisk -r
Asterisk 1.2.9.1 svn rev 34876, Copyright (C) 1999 - 2006 Digium, Inc. and others.
Created by Mark Spencer <markster@digium.com>
Asterisk comes with ABSOLUTELY NO WARRANTY; type 'show warranty' for details.
This is free software, with components licensed under the GNU General Public
License version 2 and other licenses; you are welcome to redistribute it under
certain conditions. Type 'show license' for details.
=========================================================================
Connected to Asterisk 1.2.9.1 svn rev 34876 currently running on asterisk1 (pid = 32079)
Verbosity is at least 5
Core debug is at least 1
    -- Executing Goto("SIP/202-2e74", "from-pstn|s|1") in new stack
    -- Goto (from-pstn,s,1)
    -- Executing Set("SIP/202-2e74", "FROM_DID=s") in new stack
    -- Executing Gosub("SIP/202-2e74", "cidlookup|cidlookup_1|1") in new stack
    -- Executing LookupCIDName("SIP/202-2e74", "") in new stack
    -- Executing Return("SIP/202-2e74", "") in new stack
    -- Executing Goto("SIP/202-2e74", "timeconditions|1|1") in new stack
    -- Goto (timeconditions,1,1)
    -- Executing GotoIfTime("SIP/202-2e74", "10:00-20:00|sun-thu|*|*?ivr-5|s|1") in new stack
    -- Goto (ivr-5,s,1)
    -- Executing Set("SIP/202-2e74", "LOOPCOUNT=0") in new stack
    -- Executing Set("SIP/202-2e74", "__DIR-CONTEXT=default") in new stack
    -- Executing Answer("SIP/202-2e74", "") in new stack
    -- Executing Wait("SIP/202-2e74", "1") in new stack
    -- Executing Set("SIP/202-2e74", "TIMEOUT(digit)=3") in new stack
    -- Digit timeout set to 3
    -- Executing Set("SIP/202-2e74", "TIMEOUT(response)=8") in new stack
    -- Response timeout set to 8
    -- Executing BackGround("SIP/202-2e74", "custom/MainDay") in new stack
    -- Playing 'custom/MainDay' (language 'en')
  == CDR updated on SIP/202-2e74
    -- Executing Goto("SIP/202-2e74", "timeconditions|2|1") in new stack
    -- Goto (timeconditions,2,1)
    -- Executing GotoIfTime("SIP/202-2e74", "12:00-20:00|sun-thu|*|*?ext-queues|2|1") in new stack
    -- Goto (ext-queues,2,1)
    -- Executing Answer("SIP/202-2e74", "") in new stack
    -- Executing Set("SIP/202-2e74", "__BLKVM_OVERRIDE=BLKVM/2/SIP/202-2e74") in new stack
    -- Executing Set("SIP/202-2e74", "__BLKVM_BASE=2") in new stack
    -- Executing Set("SIP/202-2e74", "DB(BLKVM/2/SIP/202-2e74)=TRUE") in new stack
    -- Executing Set("SIP/202-2e74", "_DIAL_OPTIONS=TtM(auto-blkvm)") in new stack
    -- Executing Set("SIP/202-2e74", "__NODEST=2") in new stack
    -- Executing GotoIf("SIP/202-2e74", "0?USERCID:SETCID") in new stack
    -- Goto (ext-queues,2,9)
    -- Executing Set("SIP/202-2e74", "CALLERID(name)=device") in new stack
    -- Executing Set("SIP/202-2e74", "MONITOR_FILENAME=/var/spool/asterisk/monitor/q2-20070108-193203-1168277518.1140") in new stack
    -- Executing Playback("SIP/202-2e74", "custom/transfer2Lab") in new stack
    -- Playing 'custom/transfer2Lab' (language 'en')
    -- Executing Queue("SIP/202-2e74", "2|t||") in new stack
    -- Started music on hold, class 'default', on SIP/202-2e74
    -- Called Local/203@from-internal/n
    -- Executing Macro("Local/203@from-internal-6d58,2", "exten-vm|203|203") in new stack
    -- Executing Macro("Local/203@from-internal-6d58,2", "user-callerid") in new stack
    -- Executing NoOp("Local/203@from-internal-6d58,2", "user-callerid: device 202") in new stack
    -- Executing GotoIf("Local/203@from-internal-6d58,2", "1?report") in new stack
    -- Goto (macro-user-callerid,s,11)
    -- Executing NoOp("Local/203@from-internal-6d58,2", "TTL:  ARG1: 203") in new stack
    -- Executing GotoIf("Local/203@from-internal-6d58,2", "0?continue") in new stack
    -- Executing Set("Local/203@from-internal-6d58,2", "_TTL=64") in new stack
    -- Executing GotoIf("Local/203@from-internal-6d58,2", "1?continue") in new stack
    -- Goto (macro-user-callerid,s,21)
    -- Executing NoOp("Local/203@from-internal-6d58,2", "Using CallerID "device" <202>") in new stack
    -- Executing Set("Local/203@from-internal-6d58,2", "FROMCONTEXT=exten-vm") in new stack
    -- Executing Set("Local/203@from-internal-6d58,2", "VMBOX=203") in new stack
    -- Executing Set("Local/203@from-internal-6d58,2", "EXTTOCALL=203") in new stack
    -- Executing Set("Local/203@from-internal-6d58,2", "CFUEXT=") in new stack
    -- Executing Set("Local/203@from-internal-6d58,2", "RT=45") in new stack
    -- Executing Macro("Local/203@from-internal-6d58,2", "record-enable|203|IN") in new stack
    -- Executing GotoIf("Local/203@from-internal-6d58,2", "0?2:4") in new stack
    -- Goto (macro-record-enable,s,4)
    -- Executing DeadAGI("Local/203@from-internal-6d58,2", "recordingcheck|20070108-193206|1168277526.1142") in new stack
    -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
  recordingcheck|20070108-193206|1168277526.1142: Inbound recording not enabled
    -- AGI Script recordingcheck completed, returning 0
    -- Executing NoOp("Local/203@from-internal-6d58,2", "No recording needed") in new stack
    -- Executing Macro("Local/203@from-internal-6d58,2", "dial|45|TtM(auto-blkvm)|203") in new stack
    -- Executing DeadAGI("Local/203@from-internal-6d58,2", "dialparties.agi") in new stack
    -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
  dialparties.agi: Starting New Dialparties.agi
  dialparties.agi: priority is 1
  dialparties.agi: Caller ID name is 'device' number is '202'
  dialparties.agi: Methodology of ring is  'none'
       >  dialparties.agi: USE_CONFIRMATION:  'FALSE'
       >  dialparties.agi: RINGGROUP_INDEX:   ''
    --  dialparties.agi: Added extension 203 to extension map
    --  dialparties.agi: Extension 203 cf is disabled
    --  dialparties.agi: Extension 203 do not disturb is disabled
       >  dialparties.agi: extnum: 203
       >  dialparties.agi: exthascw: 0
       >  dialparties.agi: exthascfb: 0
       >  dialparties.agi: extcfb:
       >  dialparties.agi: exthascfu: 0
       >  dialparties.agi: extcfu:
  == Manager 'admin' logged on from 127.0.0.1
  == Manager 'admin' logged off from 127.0.0.1
       >  dialparties.agi: ExtensionState: 0
  dialparties.agi: Extension 203 has ExtensionState: 0
    --  dialparties.agi: Checking CW and CFB status for extension 203
    --  dialparties.agi: dbset CALLTRACE/203 to 202
       >  dialparties.agi: NODEST: 2 blkvm enabled macro already in dialopts: TtM(auto-blkvm)
    -- AGI Script dialparties.agi completed, returning 0
    -- Executing Dial("Local/203@from-internal-6d58,2", "SIP/203|45|TtM(auto-blkvm)") in new stack
    -- Called 203
    -- SIP/203-04d0 is ringing
    -- Local/203@from-internal-6d58,1 is ringing
    -- User pressed digit: 1
    -- Stopped music on hold on SIP/202-2e74
    -- Executing Goto("SIP/202-2e74", "ext-local|*290|1") in new stack
    -- Goto (ext-local,*290,1)
    -- Executing Macro("SIP/202-2e74", "vm|290|DIRECTDIAL") in new stack
    -- Executing Macro("SIP/202-2e74", "user-callerid|SKIPTTL") in new stack
    -- Executing NoOp("SIP/202-2e74", "user-callerid: device 202") in new stack
    -- Executing GotoIf("SIP/202-2e74", "0?report") in new stack
    -- Executing GotoIf("SIP/202-2e74", "0?start") in new stack
    -- Executing Set("SIP/202-2e74", "REALCALLERIDNUM=202") in new stack
    -- Executing NoOp("SIP/202-2e74", "REALCALLERIDNUM is 202") in new stack
    -- Executing Set("SIP/202-2e74", "AMPUSER=202") in new stack
    -- Executing Set("SIP/202-2e74", "AMPUSERCIDNAME=Michirot") in new stack
    -- Executing GotoIf("SIP/202-2e74", "0?report") in new stack
    -- Executing Set("SIP/202-2e74", "CALLERID(all)=Michirot <202>") in new stack
    -- Executing Set("SIP/202-2e74", "REALCALLERIDNUM=202") in new stack
    -- Executing NoOp("SIP/202-2e74", "TTL:  ARG1: SKIPTTL") in new stack
    -- Executing GotoIf("SIP/202-2e74", "1?continue") in new stack
    -- Goto (macro-user-callerid,s,21)
    -- Executing NoOp("SIP/202-2e74", "Using CallerID "Michirot" <202>") in new stack
    -- Executing Set("SIP/202-2e74", "VMGAIN=") in new stack
    -- Executing GotoIf("SIP/202-2e74", "0?s-DIRECTDIAL|1") in new stack
    -- Executing NoOp("SIP/202-2e74", "CAME FROM: 2 - Blocking VM cause of key: ") in new stack
    -- Executing Hangup("SIP/202-2e74", "") in new stack
    -- Executing Macro("Local/203@from-internal-6d58,2", "hangupcall") in new stack
    -- Executing ResetCDR("Local/203@from-internal-6d58,2", "w") in new stack
    -- Executing NoCDR("Local/203@from-internal-6d58,2", "") in new stack
    -- Executing GotoIf("Local/203@from-internal-6d58,2", "1?skiprg") in new stack
    -- Goto (macro-hangupcall,s,6)
    -- Executing GotoIf("Local/203@from-internal-6d58,2", "1?theend") in new stack
    -- Goto (macro-hangupcall,s,9)
    -- Executing Wait("Local/203@from-internal-6d58,2", "5") in new stack



there you go. i hope its enough!

p.s. i was just trying to learn a bit, too!

01/08/07 09:34:55 changed by mbrevda

no edit feature? whre is the the bug reporter for the bug reporter??

i forgot the hangup line: 

 -- Executing Hangup("Local/203@from-internal-6d58,2", "") in new stack

01/08/07 09:41:10 changed by naftali5

on second thought... what i told you originally may be the problem... but only maybe, because it seems that the original posted debug is not from your system (is it?). Can you check /var/log/asterisk/full to see if you are getting 

Jan 1 12:40:55 WARNING[3582] func_db.c: DB requires an argument, DB(/)

in extensions.conf find this 

; If BLKVM_OVERRIDE is set, then someone told us to block calls from going to
; voicemail. This variable is reset by the answering channel so subsequent
; transfers will properly function.
;
exten => s,n,GotoIf($["foo${DB(${BLKVM_OVERRIDE})}" != "fooTRUE"]?s-${ARG2},1)
;

and change it to this 

; If BLKVM_OVERRIDE is set, then someone told us to block calls from going to
; voicemail. This variable is reset by the answering channel so subsequent
; transfers will properly function.
;
exten => s,n,NoOp(BLKVM_OVERRIDE=${BLKVM_OVERRIDE})
exten => s,n,GotoIf($["foo${BLKVM_OVERRIDE}" = "foo"]?s-${ARG2})
exten => s,n,GotoIf($["foo${DB(${BLKVM_OVERRIDE})}" != "fooTRUE"]?s-${ARG2},1)
;

it is not fixing the problem, but will probably mask it, and hopefully not break anything. probably worth a try until we figure out what is causing it.

01/08/07 09:50:39 changed by mbrevda 

[root@asterisk1 ~]# tail -n40 /var/log/asterisk/full
Jan  8 19:51:59 DEBUG[10260] pbx.c: Launching 'GotoIf'
Jan  8 19:51:59 VERBOSE[10260] logger.c:     -- Executing GotoIf("SIP/202-2e6c", "1?continue") in new stack
Jan  8 19:51:59 VERBOSE[10260] logger.c:     -- Goto (macro-user-callerid,s,21)
Jan  8 19:51:59 DEBUG[10260] pbx.c: Function result is '"Michirot" <202>'
Jan  8 19:51:59 DEBUG[10260] pbx.c: Launching 'NoOp'
Jan  8 19:51:59 VERBOSE[10260] logger.c:     -- Executing NoOp("SIP/202-2e6c", "Using CallerID "Michirot" <202>") in new stack
Jan  8 19:51:59 DEBUG[10260] pbx.c: Expression result is '0'
Jan  8 19:51:59 DEBUG[10260] pbx.c: Function result is ''
Jan  8 19:51:59 DEBUG[10260] pbx.c: Launching 'Set'
Jan  8 19:51:59 VERBOSE[10260] logger.c:     -- Executing Set("SIP/202-2e6c", "VMGAIN=") in new stack
Jan  8 19:51:59 DEBUG[10260] pbx.c: Function result is 'TRUE'
Jan  8 19:51:59 DEBUG[10260] pbx.c: Expression result is '0'
Jan  8 19:51:59 DEBUG[10260] pbx.c: Launching 'GotoIf'
Jan  8 19:51:59 VERBOSE[10260] logger.c:     -- Executing GotoIf("SIP/202-2e6c", "0?s-DIRECTDIAL|1") in new stack
Jan  8 19:51:59 DEBUG[10260] pbx.c: Not taking any branch
Jan  8 19:51:59 WARNING[10260] func_db.c: DB requires an argument, DB(<family>/<key>)
Jan  8 19:51:59 DEBUG[10260] pbx.c: Function result is ''
Jan  8 19:51:59 DEBUG[10260] pbx.c: Launching 'NoOp'
Jan  8 19:51:59 VERBOSE[10260] logger.c:     -- Executing NoOp("SIP/202-2e6c", "CAME FROM: 2 - Blocking VM cause of key: ") in new stack
Jan  8 19:51:59 DEBUG[10260] pbx.c: Launching 'Hangup'
Jan  8 19:51:59 VERBOSE[10260] logger.c:     -- Executing Hangup("SIP/202-2e6c", "") in new stack
Jan  8 19:51:59 DEBUG[10260] pbx.c: Spawn extension (ext-local,*290,2) exited non-zero on 'SIP/202-2e6c'
Jan  8 19:51:59 DEBUG[10260] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
Jan  8 19:51:59 DEBUG[10260] cdr_addon_mysql.c: cdr_mysql: SQL command as follows: INSERT INTO cdr (calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode,uniqueid) VALUES ('2007-01-08 19:51:50','\"Michirot\" <202>','202','*290','ext-local', 'SIP/202-2e6c','','Hangup','',9,9,'ANSWERED',3,'','1168278710.1167')
Jan  8 19:51:59 DEBUG[32092] chan_sip.c: Stopping retransmission on '582924c46566d30c29f0eea524127e26@192.168.0.99' of Request 102: Match Found
Jan  8 19:51:59 DEBUG[10260] channel.c: Hanging up channel 'SIP/202-2e6c'
Jan  8 19:51:59 DEBUG[10260] chan_sip.c: Hangup call SIP/202-2e6c, SIP callid 494c7c8b21da88b7@192.168.0.111)
Jan  8 19:51:59 DEBUG[10260] chan_sip.c: update_call_counter(202) - decrement call limit counter
Jan  8 19:51:59 DEBUG[10271] app_queue.c: Device 'SIP/202' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.
Jan  8 19:51:59 DEBUG[32092] chan_sip.c: Stopping retransmission on '87b639a3fa92da9c@192.168.0.111' of Request 313: Match Found
Jan  8 19:51:59 DEBUG[32092] chan_sip.c: Stopping retransmission on '582924c46566d30c29f0eea524127e26@192.168.0.99' of Request 102: Match Not Found
Jan  8 19:51:59 DEBUG[32092] chan_sip.c: Stopping retransmission on 'e022e721e9ad4b11@192.168.0.109' of Request 404: Match Found
Jan  8 19:51:59 DEBUG[32092] chan_sip.c: Stopping retransmission on '494c7c8b21da88b7@192.168.0.111' of Request 102: Match Found
Jan  8 19:51:59 DEBUG[32092] chan_sip.c: Stopping retransmission on 'ac0383edb466f85f@192.168.0.111' of Request 273: Match Found
Jan  8 19:52:04 DEBUG[10263] pbx.c: Launching 'Hangup'
Jan  8 19:52:04 VERBOSE[10263] logger.c:     -- Executing Hangup("Local/203@from-internal-7d9b,2", "") in new stack
Jan  8 19:52:04 DEBUG[10263] app_macro.c: Spawn extension (macro-hangupcall,s,10) exited non-zero on 'Local/203@from-internal-7d9b,2' in macro 'hangupcall'
Jan  8 19:52:04 DEBUG[10263] pbx.c: Spawn extension (macro-hangupcall,s,10) exited non-zero on 'Local/203@from-internal-7d9b,2'
Jan  8 19:52:04 DEBUG[10263] channel.c: Hanging up channel 'Local/203@from-internal-7d9b,2'
Jan  8 19:52:04 DEBUG[10273] app_queue.c: Device 'Local/203@from-internal' changed to state '0' (Unknown) but we don't care because they're not a member of any queue.

01/08/07 09:54:39 changed by mbrevda

No love!

with the changes, i get the same error: 

    -- Executing NoOp("SIP/202-57c4", "Using CallerID "Michirot" <202>") in new stack
    -- Executing Set("SIP/202-57c4", "VMGAIN=") in new stack
    -- Executing NoOp("SIP/202-57c4", "BLKVM_OVERRIDE=BLKVM/2/SIP/202-57c4") in new stack
    -- Executing GotoIf("SIP/202-57c4", "0?s-DIRECTDIAL") in new stack
    -- Executing GotoIf("SIP/202-57c4", "0?s-DIRECTDIAL|1") in new stack
    -- Executing NoOp("SIP/202-57c4", "CAME FROM: 2 - Blocking VM cause of key: ") in new stack
    -- Executing Hangup("SIP/202-57c4", "") in new stack
    -- Executing Macro("Local/203@from-internal-9bca,2", "hangupcall") in new stack
    -- Executing ResetCDR("Local/203@from-internal-9bca,2", "w") in new stack
    -- Executing NoCDR("Local/203@from-internal-9bca,2", "") in new stack
    -- Executing GotoIf("Local/203@from-internal-9bca,2", "1?skiprg") in new stack
    -- Goto (macro-hangupcall,s,6)
    -- Executing GotoIf("Local/203@from-internal-9bca,2", "1?theend") in new stack
    -- Goto (macro-hangupcall,s,9)
    -- Executing Wait("Local/203@from-internal-9bca,2", "5") in new stack

01/08/07 10:04:52 changed by naftali5

Indeed, i was right. The original post which i spent time figuring out, is not from your system, or at least not the problem you are having! I'll work on your debug now...

01/08/07 10:08:24 changed by mbrevda

never said it was! i figured that this line is the key here and thats IS the same: 

Blocking VM cause of key: "

01/08/07 11:46:44 changed by

  • milestone deleted.

Milestone 2.2 deleted

01/08/07 11:51:59 changed by vgster

  • milestone set to 2.2.

01/09/07 17:12:45 changed by dgoner

I'm having the same issue on a fresh Trixbox 2.0 final install with FreePBX 2.2.0 When a caller chooses to leave the queue and leave a voicemail option (1) they are disconnected. Changing the IVR design to move the caller to another IVR works fine. It appears isolated to users trying to leave a queue to an extensions voicemail. My debug output is below... 

 -- Goto (macro-user-callerid,s,11)
-- Executing NoOp("Local/205@from-internal-ad03,2", "TTL: 63 ARG1: SKIPTTL") in new stack
-- Executing GotoIf("Local/205@from-internal-ad03,2", "1?continue") in new stack
-- Goto (macro-user-callerid,s,21)
-- Executing NoOp("Local/205@from-internal-ad03,2", "Using CallerID "Dispatch:8156763300" <8156763300>") in new stack
-- Executing Set("Local/205@from-internal-ad03,2", "VMGAIN=") in new stack
-- Executing GotoIf("Local/205@from-internal-ad03,2", "0?s-CHANUNAVAIL|1") in new stack
-- Executing NoOp("Local/205@from-internal-ad03,2", "CAME FROM: 600 - Blocking VM cause of key: ") in new stack
-- Executing Hangup("Local/205@from-internal-ad03,2", "") in new stack
== Spawn extension (from-internal, 205, 2) exited non-zero on 'Local/205@from-internal-ad03,2'
-- Executing Macro("Local/205@from-internal-ad03,2", "hangupcall") in new stack
-- Executing ResetCDR("Local/205@from-internal-ad03,2", "w") in new stack
-- AGI Script dialparties.agi completed, returning 0
-- Executing Dial("Local/205@from-internal-ad03,2", "SIP/205|15|trM(auto-blkvm)") in new stack
== Everyone is busy/congested at this time (1:0/0/1)
-- Executing GosubIf("Local/205@from-internal-ad03,2", "0?docfu|1") in new stack
-- Executing NoOp("Local/205@from-internal-ad03,2", "Voicemail is 205") in new stack
-- Executing GotoIf("Local/205@from-internal-ad03,2", "0?s-CHANUNAVAIL|1") in new stack
-- Executing NoCDR("Local/205@from-internal-ad03,2", "") in new stack
-- Executing GotoIf("Local/205@from-internal-ad03,2", "1?skiprg") in new stack
-- Goto (macro-hangupcall,s,6)
-- Executing GotoIf("Local/205@from-internal-ad03,2", "1?theend") in new stack
-- Goto (macro-hangupcall,s,9)
-- Executing Wait("Local/205@from-internal-ad03,2", "5") in new stack
/205@from-internal-ad03,2", "Sending to Voicemail box 205") in new stack
-- Executing Macro("Local/205@from-internal-ad03,2", "vm|205|CHANUNAVAIL") in new stack
-- Executing Macro("Local/205@from-internal-ad03,2", "user-callerid|SKIPTTL") in new stack
-- Executing NoOp("Local/205@from-internal-ad03,2", "user-callerid: Dispatch:8156763300 8156763300") in new stack
-- Executing GotoIf("Local/205@from-internal-ad03,2", "1?report") in new stack
-- Goto (macro-user-callerid,s,11)
-- Executing NoOp("Local/205@from-internal-ad03,2", "TTL: 63 ARG1: SKIPTTL") in new stack
-- Executing GotoIf("Local/205@from-internal-ad03,2", "1?continue") in new stack
-- Goto (macro-user-callerid,s,21)
-- Executing NoOp("Local/205@from-internal-ad03,2", "Using CallerID "Dispatch:8156763300" <8156763300>") in new stack
-- Executing Set("Local/205@from-internal-ad03,2", "VMGAIN=") in new stack
-- Executing GotoIf("Local/205@from-internal-ad03,2", "0?s-CHANUNAVAIL|1") in new stack
-- Executing NoOp("Local/205@from-internal-ad03,2", "CAME FROM: 600 - Blocking VM cause of key: ") in new stack
-- Executing Hangup("Local/205@from-internal-ad03,2", "") in new stack
== Spawn extension (from-internal, 205, 2) exited non-zero on 'Local/205@from-internal-ad03,2'
-- Executing Macro("Local/205@from-internal-ad03,2", "hangupcall") in new stack
-- Executing ResetCDR("Local/205@from-internal-ad03,2", "w") in new stack
-- Executing NoCDR("Local/205@from-internal-ad03,2", "") in new stack
-- Executing GotoIf("Local/205@from-internal-ad03,2", "1?skiprg") in new stack
-- Goto (macro-hangupcall,s,6)
-- Executing GotoIf("Local/205@from-internal-ad03,2", "1?theend") in new stack
-- Goto (macro-hangupcall,s,9)
-- Executing Wait("Local/205@from-internal-ad03,2", "5") in new stack
recordingcheck|20070109-142039|1168374039.56: Inbound recording not enabled
recordingcheck|20070109-142039|1168374039.56: Inbound recording not enabled
-- AGI Script recordingcheck completed, returning 0
-- Executing NoOp("Local/202@from-internal-1bf0,2", "No recording needed") in new stack
-- Executing Macro("Local/202@from-internal-1bf0,2", "dial|15|trM(auto-blkvm)|202") in new stack
-- Executing DeadAGI("Local/202@from-internal-1bf0,2", "dialparties.agi") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
-- AGI Script recordingcheck completed, returning 0
-- Executing NoOp("Local/202@from-internal-1bf0,2", "No recording needed") in new stack
-- Executing Macro("Local/202@from-internal-1bf0,2", "dial|15|trM(auto-blkvm)|202") in new stack
-- Executing DeadAGI("Local/202@from-internal-1bf0,2", "dialparties.agi") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
dialparties.agi: Starting New Dialparties.agi
dialparties.agi: Starting New Dialparties.agi
dialparties.agi: priority is 1
dialparties.agi: priority is 1
dialparties.agi: Caller ID name is 'Dispatch:8156763300' number is '8156763300'
dialparties.agi: Caller ID name is 'Dispatch:8156763300' number is '8156763300'
dialparties.agi: Methodology of ring is 'none'
dialparties.agi: Methodology of ring is 'none'
> dialparties.agi: USE_CONFIRMATION: 'FALSE'
> dialparties.agi: USE_CONFIRMATION: 'FALSE'
> dialparties.agi: RINGGROUP_INDEX: ''
> dialparties.agi: RINGGROUP_INDEX: ''
-- dialparties.agi: Added extension 202 to extension map
-- dialparties.agi: Added extension 202 to extension map
-- dialparties.agi: Extension 202 cf is disabled
-- dialparties.agi: Extension 202 cf is disabled
-- dialparties.agi: Extension 202 do not disturb is disabled
-- dialparties.agi: Extension 202 do not disturb is disabled
> dialparties.agi: extnum: 202
> dialparties.agi: extnum: 202
> dialparties.agi: exthascw: 1
> dialparties.agi: exthascw: 1
> dialparties.agi: exthascfb: 0
> dialparties.agi: exthascfb: 0
> dialparties.agi: extcfb:
> dialparties.agi: extcfb:
> dialparties.agi: exthascfu: 0
> dialparties.agi: exthascfu: 0
> dialparties.agi: extcfu:
> dialparties.agi: extcfu:
-- dialparties.agi: dbset CALLTRACE/202 to 8156763300
-- dialparties.agi: dbset CALLTRACE/202 to 8156763300
> dialparties.agi: NODEST: 600 blkvm enabled macro already in dialopts: trM(auto-blkvm)
> dialparties.agi: NODEST: 600 blkvm enabled macro already in dialopts: trM(auto-blkvm)
-- AGI Script dialparties.agi completed, returning 0
-- Executing Dial("Local/202@from-internal-1bf0,2", "SIP/202|15|trM(auto-blkvm)") in new stack
== Everyone is busy/congested at this time (1:0/0/1)
-- Executing GosubIf("Local/202@from-internal-1bf0,2", "0?docfu|1") in new stack
-- Executing NoOp("Local/202@from-internal-1bf0,2", "Voicemail is 202") in new stack
-- Executing GotoIf("Local/202@from-internal-1bf0,2", "0?s-CHANUNAVAIL|1") in new stack
-- Executing NoOp("Local/202@from-internal-1bf0,2", "Sending to Voicemail box 202") in new stack
-- Executing Macro("Local/202@from-internal-1bf0,2", "vm|202|CHANUNAVAIL") in new stack
-- Executing Macro("Local/202@from-internal-1bf0,2", "user-callerid|SKIPTTL") in new stack
-- Executing NoOp("Local/202@from-internal-1bf0,2", "user-callerid: Dispatch:8156763300 8156763300") in new stack
-- Executing GotoIf("Local/202@from-internal-1bf0,2", "1?report") in new stack
-- Goto (macro-user-callerid,s,11)
-- Executing NoOp("Local/202@from-internal-1bf0,2", "TTL: 63 ARG1: SKIPTTL") in new stack
-- Executing GotoIf("Local/202@from-internal-1bf0,2", "1?continue") in new stack
-- Goto (macro-user-callerid,s,21)
-- Executing NoOp("Local/202@from-internal-1bf0,2", "Using CallerID "Dispatch:8156763300" <8156763300>") in new stack
-- Executing Set("Local/202@from-internal-1bf0,2", "VMGAIN=") in new stack
-- Executing GotoIf("Local/202@from-internal-1bf0,2", "0?s-CHANUNAVAIL|1") in new stack
-- Executing NoOp("Local/202@from-internal-1bf0,2", "CAME FROM: 600 - Blocking VM cause of key: ") in new stack
-- Executing Hangup("Local/202@from-internal-1bf0,2", "") in new stack
== Spawn extension (from-internal, 202, 2) exited non-zero on 'Local/202@from-internal-1bf0,2'
-- Executing Macro("Local/202@from-internal-1bf0,2", "hangupcall") in new stack
-- Executing ResetCDR("Local/202@from-internal-1bf0,2", "w") in new stack
-- AGI Script dialparties.agi completed, returning 0
-- Executing Dial("Local/202@from-internal-1bf0,2", "SIP/202|15|trM(auto-blkvm)") in new stack
== Everyone is busy/congested at this time (1:0/0/1)
-- Executing GosubIf("Local/202@from-internal-1bf0,2", "0?docfu|1") in new stack
-- Executing NoOp("Local/202@from-internal-1bf0,2", "Voicemail is 202") in new stack
-- Executing GotoIf("Local/202@from-internal-1bf0,2", "0?s-CHANUNAVAIL|1") in new stack
-- Executing NoCDR("Local/202@from-internal-1bf0,2", "") in new stack
-- Executing GotoIf("Local/202@from-internal-1bf0,2", "1?skiprg") in new stack
-- Goto (macro-hangupcall,s,6)
-- Executing GotoIf("Local/202@from-internal-1bf0,2", "1?theend") in new stack
-- Goto (macro-hangupcall,s,9)
-- Executing Wait("Local/202@from-internal-1bf0,2", "5") in new stack
/202@from-internal-1bf0,2", "Sending to Voicemail box 202") in new stack
-- Executing Macro("Local/202@from-internal-1bf0,2", "vm|202|CHANUNAVAIL") in new stack
-- Executing Macro("Local/202@from-internal-1bf0,2", "user-callerid|SKIPTTL") in new stack
-- Executing NoOp("Local/202@from-internal-1bf0,2", "user-callerid: Dispatch:8156763300 8156763300") in new stack
-- Executing GotoIf("Local/202@from-internal-1bf0,2", "1?report") in new stack
-- Goto (macro-user-callerid,s,11)
-- Executing NoOp("Local/202@from-internal-1bf0,2", "TTL: 63 ARG1: SKIPTTL") in new stack
-- Executing GotoIf("Local/202@from-internal-1bf0,2", "1?continue") in new stack
-- Goto (macro-user-callerid,s,21)
-- Executing NoOp("Local/202@from-internal-1bf0,2", "Using CallerID "Dispatch:8156763300" <8156763300>") in new stack
-- Executing Set("Local/202@from-internal-1bf0,2", "VMGAIN=") in new stack
-- Executing GotoIf("Local/202@from-internal-1bf0,2", "0?s-CHANUNAVAIL|1") in new stack
-- Executing NoOp("Local/202@from-internal-1bf0,2", "CAME FROM: 600 - Blocking VM cause of key: ") in new stack
-- Executing Hangup("Local/202@from-internal-1bf0,2", "") in new stack
== Spawn extension (from-internal, 202, 2) exited non-zero on 'Local/202@from-internal-1bf0,2'
-- Executing Macro("Local/202@from-internal-1bf0,2", "hangupcall") in new stack
-- Executing ResetCDR("Local/202@from-internal-1bf0,2", "w") in new stack
-- Executing NoCDR("Local/202@from-internal-1bf0,2", "") in new stack
-- Executing GotoIf("Local/202@from-internal-1bf0,2", "1?skiprg") in new stack
-- Goto (macro-hangupcall,s,6)
-- Executing GotoIf("Local/202@from-internal-1bf0,2", "1?theend") in new stack
-- Goto (macro-hangupcall,s,9)
-- Executing Wait("Local/202@from-internal-1bf0,2", "5") in new stack
-- User pressed digit: 1
-- Stopped music on hold on SIP/dgagnon-09cfedd8
-- Executing Goto("SIP/dgagnon-09cfedd8", "ext-local|*203|1") in new stack
-- Goto (ext-local,*203,1)
-- Executing Macro("SIP/dgagnon-09cfedd8", "vm|203|DIRECTDIAL") in new stack
-- Executing Macro("SIP/dgagnon-09cfedd8", "user-callerid|SKIPTTL") in new stack
-- Executing NoOp("SIP/dgagnon-09cfedd8", "user-callerid: Dispatch:8156763300 8156763300") in new stack
-- Executing GotoIf("SIP/dgagnon-09cfedd8", "0?report") in new stack
-- Executing GotoIf("SIP/dgagnon-09cfedd8", "1?start") in new stack
-- Goto (macro-user-callerid,s,5)
-- Executing NoOp("SIP/dgagnon-09cfedd8", "REALCALLERIDNUM is 8156763300") in new stack
-- Executing Set("SIP/dgagnon-09cfedd8", "AMPUSER=") in new stack
-- Executing Set("SIP/dgagnon-09cfedd8", "AMPUSERCIDNAME=") in new stack
-- Executing GotoIf("SIP/dgagnon-09cfedd8", "1?report") in new stack
-- Goto (macro-user-callerid,s,11)
-- Executing NoOp("SIP/dgagnon-09cfedd8", "TTL: 64 ARG1: SKIPTTL") in new stack
-- Executing GotoIf("SIP/dgagnon-09cfedd8", "1?continue") in new stack
-- Goto (macro-user-callerid,s,21)
-- Executing NoOp("SIP/dgagnon-09cfedd8", "Using CallerID "Dispatch:8156763300" <8156763300>") in new stack
-- Executing Set("SIP/dgagnon-09cfedd8", "VMGAIN=") in new stack
-- Executing GotoIf("SIP/dgagnon-09cfedd8", "0?s-DIRECTDIAL|1") in new stack
-- Executing NoOp("SIP/dgagnon-09cfedd8", "CAME FROM: 600 - Blocking VM cause of key: ") in new stack
-- Executing Hangup("SIP/dgagnon-09cfedd8", "") in new stack
== Spawn extension (ext-local, *203, 2) exited non-zero on 'SIP/dgagnon-09cfedd8'
== Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'Local/201@from-internal-4293,2' in macro 'hangupcall'
== Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'Local/201@from-internal-4293,2'
== Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'Local/202@from-internal-1bf0,2' in macro 'hangupcall'
== Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'Local/202@from-internal-1bf0,2'
== Spawn extension (macro-dial, s, 10) exited non-zero on 'Local/203@from-internal-8b46,2' in macro 'dial'
== Spawn extension (macro-dial, s, 10) exited non-zero on 'Local/203@from-internal-8b46,2' in macro 'exten-vm'
== Spawn extension (macro-dial, s, 10) exited non-zero on 'Local/203@from-internal-8b46,2'
-- Executing Macro("Local/203@from-internal-8b46,2", "hangupcall") in new stack
-- Executing ResetCDR("Local/203@from-internal-8b46,2", "w") in new stack
== Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'Local/204@from-internal-7bba,2' in macro 'hangupcall'
== Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'Local/204@from-internal-7bba,2'
== Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'Local/205@from-internal-ad03,2' in macro 'hangupcall'
== Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'Local/205@from-internal-ad03,2'
-- User pressed digit: 1
-- Stopped music on hold on SIP/dgagnon-09cfedd8
-- Executing Goto("SIP/dgagnon-09cfedd8", "ext-local|*203|1") in new stack
-- Goto (ext-local,*203,1)
-- Executing Macro("SIP/dgagnon-09cfedd8", "vm|203|DIRECTDIAL") in new stack
-- Executing Macro("SIP/dgagnon-09cfedd8", "user-callerid|SKIPTTL") in new stack
-- Executing NoOp("SIP/dgagnon-09cfedd8", "user-callerid: Dispatch:8156763300 8156763300") in new stack
-- Executing NoCDR("Local/203@from-internal-8b46,2", "") in new stack
-- Executing GotoIf("Local/203@from-internal-8b46,2", "1?skiprg") in new stack
-- Goto (macro-hangupcall,s,6)
-- Executing GotoIf("Local/203@from-internal-8b46,2", "1?theend") in new stack
-- Goto (macro-hangupcall,s,9)
-- Executing Wait("Local/203@from-internal-8b46,2", "5") in new stack
9cfedd8", "0?report") in new stack
-- Executing GotoIf("SIP/dgagnon-09cfedd8", "1?start") in new stack
-- Goto (macro-user-callerid,s,5)
-- Executing NoOp("SIP/dgagnon-09cfedd8", "REALCALLERIDNUM is 8156763300") in new stack
-- Executing Set("SIP/dgagnon-09cfedd8", "AMPUSER=") in new stack
-- Executing Set("SIP/dgagnon-09cfedd8", "AMPUSERCIDNAME=") in new stack
-- Executing GotoIf("SIP/dgagnon-09cfedd8", "1?report") in new stack
-- Goto (macro-user-callerid,s,11)
-- Executing NoOp("SIP/dgagnon-09cfedd8", "TTL: 64 ARG1: SKIPTTL") in new stack
-- Executing GotoIf("SIP/dgagnon-09cfedd8", "1?continue") in new stack
-- Goto (macro-user-callerid,s,21)
-- Executing NoOp("SIP/dgagnon-09cfedd8", "Using CallerID "Dispatch:8156763300" <8156763300>") in new stack
-- Executing Set("SIP/dgagnon-09cfedd8", "VMGAIN=") in new stack
-- Executing GotoIf("SIP/dgagnon-09cfedd8", "0?s-DIRECTDIAL|1") in new stack
-- Executing NoOp("SIP/dgagnon-09cfedd8", "CAME FROM: 600 - Blocking VM cause of key: ") in new stack
-- Executing Hangup("SIP/dgagnon-09cfedd8", "") in new stack
== Spawn extension (ext-local, *203, 2) exited non-zero on 'SIP/dgagnon-09cfedd8'
== Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'Local/201@from-internal-4293,2' in macro 'hangupcall'
== Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'Local/201@from-internal-4293,2'
== Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'Local/202@from-internal-1bf0,2' in macro 'hangupcall'
== Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'Local/202@from-internal-1bf0,2'
== Spawn extension (macro-dial, s, 10) exited non-zero on 'Local/203@from-internal-8b46,2' in macro 'dial'
== Spawn extension (macro-dial, s, 10) exited non-zero on 'Local/203@from-internal-8b46,2' in macro 'exten-vm'
== Spawn extension (macro-dial, s, 10) exited non-zero on 'Local/203@from-internal-8b46,2'
-- Executing Macro("Local/203@from-internal-8b46,2", "hangupcall") in new stack
-- Executing ResetCDR("Local/203@from-internal-8b46,2", "w") in new stack
== Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'Local/204@from-internal-7bba,2' in macro 'hangupcall'
== Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'Local/204@from-internal-7bba,2'
== Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'Local/205@from-internal-ad03,2' in macro 'hangupcall'
== Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'Local/205@from-internal-ad03,2'
-- Executing NoCDR("Local/203@from-internal-8b46,2", "") in new stack
-- Executing GotoIf("Local/203@from-internal-8b46,2", "1?skiprg") in new stack
-- Goto (macro-hangupcall,s,6)
-- Executing GotoIf("Local/203@from-internal-8b46,2", "1?theend") in new stack
-- Goto (macro-hangupcall,s,9)
-- Executing Wait("Local/203@from-internal-8b46,2", "5") in new stack
-- Executing Hangup("Local/203@from-internal-8b46,2", "") in new stack
== Spawn extension (macro-hangupcall, s, 10) exited non-zero on 'Local/203@from-internal-8b46,2' in macro 'hangupcall'
== Spawn extension (macro-hangupcall, s, 10) exited non-zero on 'Local/203@from-internal-8b46,2'
-- Executing Hangup("Local/203@from-internal-8b46,2", "") in new stack
== Spawn extension (macro-hangupcall, s, 10) exited non-zero on 'Local/203@from-internal-8b46,2' in macro 'hangupcall'
== Spawn extension (macro-hangupcall, s, 10) exited non-zero on 'Local/203@from-internal-8b46,2'

01/09/07 20:19:05 changed by naftali5

patch posted in #1664

01/10/07 03:54:18 changed by mbrevda

naftali5: U DA MAN!!!

Thanks for the patch.

admin's: u can close this ticket. just update the ivr module as per naftali5's instructions

01/10/07 19:34:19 changed by p_lindheimer

  • engine set to Asterisk <1.2.
  • status changed from new to closed.
  • resolution set to fixed.
  • engine_version changed.
  • svn_rev changed.

fixed in r3564, r3565, r3566 (naftali5's patch #1664)