I have asterisk-Freepbx (Version 12) hosted on a debian 7 server. I have created an extension (Cisco IP phone SPA 504G). I am able to dial in and out. The extension is configured to go to voicemail if unanswered, busy or unavailable. However, if the extension is not answered, it goes to a busy signal and not the voicemail. When I look at the voicemail set up, it says it is ‘unactivated’. I cant figure out how to get this to work or activate it.
Many thanks for any suggestions
Enable it per extension. Go to the extension and enable it (look towards the bottom of the extension page)
I already have this enabled (application>extension> status>enabled). When I go to settings>voicemail admin, this is what I see:
Jas <101> (default) [unactivated]
And the following:
Number of Accounts: 1 ( 0 / 1 / 0 )
When I dial this extension and it is not answered, I have it set up to go to voicemail, but instead, it goes to a busy signal mode.
I would appreciate any help
Please post the CLI output from when you call the extension and when it goes to a busy signal.
Thanks Andrew.
[2014-06-30 18:24:55] WARNING[32145] pbx.c: No application ‘VoiceMail’ for extension (macro-vm, s-NOANSWER, 2)
[2014-06-30 18:26:07] WARNING[32158] pbx.c: No application ‘VoiceMail’ for extension (macro-vm, s-NOANSWER, 2)
I assume you wanted the asterisk logs.
What version of Asterisk are you running?
Asterisk 1.8.13.1~dfsg1-3+deb7u3 built by pbuilder @ pungenday on a x86_64 running Linux
Actually I honestly think your problem is the same as this thread
http://community.freepbx.org/t/no-application-voicemail-for-extension?source_topic_id=22892Do NOT apt-get install asterisk (less so dahdi), they are very old and put things in the wrong place, you are in for a whole world of heartache. Just follow any of the numerous recipes for installing it from source, there is even one here in the wiki 
Below is the verbose debug info:
[2014-06-30 20:17:35] DEBUG[3125] acl.c: For destination ‘888.777.666.111’, our source
address is 111.000.000.111. [2014-06-30 20:17:35] DEBUG[3125] chan_sip.c: Target address 888.777.666.111:5060 is not
local, substituting externaddr [2014-06-30 20:17:35] DEBUG[3125] chan_sip.c: Setting SIP_TRANSPORT_UDP with address
111.000.000.111:5060 [2014-06-30 20:17:35] DEBUG[3125] chan_sip.c: Allocating new SIP dialog for dac7fd13-
[email protected] - NOTIFY (No RTP) [2014-06-30 20:17:35] DEBUG[3125] chan_sip.c: **** Received NOTIFY (4) - Command in SIP
NOTIFY [2014-06-30 20:17:35] DEBUG[3125] chan_sip.c: Trying to put ‘SIP/2.0 489’ onto UDP socket
destined for 888.777.666.111:5060 [2014-06-30 20:17:35] DEBUG[3125] chan_sip.c: SIP message could not be handled, bad request:
[email protected] [2014-06-30 20:17:35] DEBUG[3125] chan_sip.c: = Looking for Call ID:
[email protected]:5060 (Checking To) --From tag as5387a74c –
To-tag 22a0f837d6d0aeb9i0 [2014-06-30 20:17:35] DEBUG[3125] chan_sip.c: Acked pending invite 102 [2014-06-30 20:17:35] DEBUG[3125] chan_sip.c: Stopping retransmission on
‘[email protected]:5060’ of Request 102: Match Found [2014-06-30 20:17:35] VERBOSE[3125] chan_sip.c: – Got SIP response 486 “Busy Here” back from
888.777.666.111:5060 [2014-06-30 20:17:35] DEBUG[3125] res_rtp_asterisk.c: Setting RTCP address on RTP instance
‘0x35b7eb8’ [2014-06-30 20:17:35] DEBUG[3125] netsock2.c: Splitting ‘192.168.10.9:5060’ into… [2014-06-30 20:17:35] DEBUG[3125] netsock2.c: …host ‘192.168.10.9’ and port ‘5060’. [2014-06-30 20:17:35] DEBUG[3125] chan_sip.c: Trying to put ‘ACK sip:101’ onto UDP socket
destined for 888.777.666.111:5060 [2014-06-30 20:17:35] DEBUG[3125] chan_sip.c: Setting SIP_ALREADYGONE on dialog
[email protected]:5060 [2014-06-30 20:17:35] VERBOSE[32392] app_dial.c: – SIP/101-00004916 is busy [2014-06-30 20:17:35] DEBUG[32392] channel.c: Hanging up channel ‘SIP/101-00004916’ [2014-06-30 20:17:35] DEBUG[32392] chan_sip.c: Hanging up zombie call. Be scared. [2014-06-30 20:17:35] DEBUG[32392] chan_sip.c: Updating call counter for outgoing call [2014-06-30 20:17:35] DEBUG[32392] res_rtp_asterisk.c: Setting RTCP address on RTP instance
‘0x35b7eb8’ [2014-06-30 20:17:35] VERBOSE[32392] app_dial.c: == Everyone is busy/congested at this time
(1:1/0/0) [2014-06-30 20:17:35] DEBUG[32392] app_dial.c: Exiting with DIALSTATUS=BUSY. [2014-06-30 20:17:35] DEBUG[32392] app_macro.c: Executed application: Dial [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘DIALSTATUS’ is ‘BUSY’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘CALLER_DEST’ is NULL [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Expression result is ‘0’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Launching ‘ExecIf’ [2014-06-30 20:17:35] VERBOSE[32392] pbx.c: – Executing [s@macro-dial-one:43] ExecIf
(“SIP/123456-00004915”, “0?MacroExit()”) in new stack [2014-06-30 20:17:35] DEBUG[32392] app_macro.c: Executed application: ExecIf [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Evaluating ‘DIALSTATUS’ (from
‘DIALSTATUS}"=“ANSWER” & “${CALLER_DEST}”!=""’ len 10) [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘DIALSTATUS’ is ‘BUSY’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Evaluating ‘CALLER_DEST’ (from ‘CALLER_DEST}"!=""’
len 11) [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘CALLER_DEST’ is NULL [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Expression result is ‘0’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘DIALSTATUS_CW’ is ‘’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Expression result is ‘0’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘DIALSTATUS_CW’ is ‘’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Launching ‘ExecIf’ [2014-06-30 20:17:35] VERBOSE[32392] pbx.c: – Executing [s@macro-dial-one:44] ExecIf
(“SIP/123456-00004915”, “0?Set(DIALSTATUS=)”) in new stack [2014-06-30 20:17:35] DEBUG[32392] app_macro.c: Executed application: ExecIf [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Evaluating ‘DIALSTATUS_CW’ (from 'DIALSTATUS_CW}"!
=""’ len 13) [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘DIALSTATUS_CW’ is ‘’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Expression result is ‘0’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Evaluating ‘DIALSTATUS_CW’ (from ‘DIALSTATUS_CW})’
len 13) [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘DIALSTATUS_CW’ is ‘’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘SCREEN’ is NULL [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘DIALSTATUS’ is ‘BUSY’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘DIALSTATUS’ is ‘BUSY’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘DIALSTATUS’ is ‘BUSY’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Expression result is ‘0’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘DIALSTATUS’ is ‘BUSY’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Launching ‘GosubIf’ [2014-06-30 20:17:35] VERBOSE[32392] pbx.c: – Executing [s@macro-dial-one:45] GosubIf
(“SIP/123456-00004915”, “0?s-BUSY,1()”) in new stack [2014-06-30 20:17:35] DEBUG[32392] app_macro.c: Executed application: GosubIf [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Evaluating ‘SCREEN’ (from 'SCREEN}"!=""&
("${DIALSTATUS}"=“TORTURE”|"${DIALSTATUS}"=“DONTCALL”))|"${DIALSTATUS}"=“ANSWER”’ len 6) [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘SCREEN’ is NULL [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Evaluating ‘DIALSTATUS’ (from
‘DIALSTATUS}"=“TORTURE”|"${DIALSTATUS}"=“DONTCALL”))|"${DIALSTATUS}"=“ANSWER”’ len 10) [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘DIALSTATUS’ is ‘BUSY’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Evaluating ‘DIALSTATUS’ (from
‘DIALSTATUS}"=“DONTCALL”))|"${DIALSTATUS}"=“ANSWER”’ len 10) [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘DIALSTATUS’ is ‘BUSY’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Evaluating ‘DIALSTATUS’ (from
‘DIALSTATUS}"=“ANSWER”’ len 10) [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘DIALSTATUS’ is ‘BUSY’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Expression result is ‘0’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Evaluating ‘DIALSTATUS’ (from ‘DIALSTATUS},1()’ len
(“SIP/123456-00004915”, “”) in new stack [2014-06-30 20:17:35] DEBUG[32392] app_macro.c: Executed application: Macro [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘DIALSTATUS’ is ‘BUSY’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Launching ‘Set’ [2014-06-30 20:17:35] VERBOSE[32392] pbx.c: – Executing [s@macro-exten-vm:15] Set
(“SIP/123456-00004915”, “SV_DIALSTATUS=BUSY”) in new stack [2014-06-30 20:17:35] DEBUG[32392] app_macro.c: Executed application: Set [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘SV_DIALSTATUS’ is ‘BUSY’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘SV_DIALSTATUS’ is ‘BUSY’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘EXTTOCALL’ is ‘101’ [2014-06-30 20:17:35] DEBUG[32392] db.c: Unable to find key ‘101’ in family ‘CFU’ [2014-06-30 20:17:35] DEBUG[32392] func_db.c: DB: CFU/101 not found in database. [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Function result is ‘’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘SCREEN’ is NULL [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Expression result is ‘0’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Launching ‘GosubIf’ [2014-06-30 20:17:35] VERBOSE[32392] pbx.c: – Executing [s@macro-exten-vm:16] GosubIf
(“SIP/123456-00004915”, “0?docfu,1()”) in new stack [2014-06-30 20:17:35] DEBUG[32392] app_macro.c: Executed application: GosubIf [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Evaluating ‘SV_DIALSTATUS’ (from
'SV_DIALSTATUS}"=“NOANSWER”|"${SV_DIALSTATUS}"=“CHANUNAVAIL”) & “${DB(CFU/${EXTTOCALL})}”!=""
& “${SCREEN}”=""’ len 13) [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘SV_DIALSTATUS’ is ‘BUSY’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Evaluating ‘SV_DIALSTATUS’ (from
‘SV_DIALSTATUS}"=“CHANUNAVAIL”) & “${DB(CFU/${EXTTOCALL})}”!="" & “${SCREEN}”=""’ len 13) [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘SV_DIALSTATUS’ is ‘BUSY’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Evaluating ‘DB(CFU/${EXTTOCALL})’ (from 'DB(CFU/
${EXTTOCALL})}"!="" & “${SCREEN}”=""’ len 20) [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Evaluating ‘EXTTOCALL’ (from ‘EXTTOCALL})’ len 9) [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘EXTTOCALL’ is ‘101’ [2014-06-30 20:17:35] DEBUG[32392] db.c: Unable to find key ‘101’ in family ‘CFU’ [2014-06-30 20:17:35] DEBUG[32392] func_db.c: DB: CFU/101 not found in database. [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Function result is ‘’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Evaluating ‘SCREEN’ (from ‘SCREEN}"=""’ len 6) [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘SCREEN’ is NULL [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Expression result is ‘0’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘SV_DIALSTATUS’ is ‘BUSY’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘EXTTOCALL’ is ‘101’ [2014-06-30 20:17:35] DEBUG[32392] db.c: Unable to find key ‘101’ in family ‘CFB’ [2014-06-30 20:17:35] DEBUG[32392] func_db.c: DB: CFB/101 not found in database. [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Function result is ‘’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘SCREEN’ is NULL [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Expression result is ‘0’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Launching ‘GosubIf’ [2014-06-30 20:17:35] VERBOSE[32392] pbx.c: – Executing [s@macro-exten-vm:17] GosubIf
(“SIP/123456-00004915”, “0?docfb,1()”) in new stack [2014-06-30 20:17:35] DEBUG[32392] app_macro.c: Executed application: GosubIf [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Evaluating ‘SV_DIALSTATUS’ (from
‘SV_DIALSTATUS}"=“BUSY” & “${DB(CFB/${EXTTOCALL})}”!="" & “${SCREEN}”=""’ len 13) [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘SV_DIALSTATUS’ is ‘BUSY’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Evaluating ‘DB(CFB/${EXTTOCALL})’ (from 'DB(CFB/
${EXTTOCALL})}"!="" & “${SCREEN}”=""’ len 20) [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Evaluating ‘EXTTOCALL’ (from ‘EXTTOCALL})’ len 9) [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘EXTTOCALL’ is ‘101’ [2014-06-30 20:17:35] DEBUG[32392] db.c: Unable to find key ‘101’ in family ‘CFB’ [2014-06-30 20:17:35] DEBUG[32392] func_db.c: DB: CFB/101 not found in database. [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Function result is ‘’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Evaluating ‘SCREEN’ (from ‘SCREEN}"=""’ len 6) [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘SCREEN’ is NULL [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Expression result is ‘0’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘SV_DIALSTATUS’ is ‘BUSY’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Launching ‘Set’ [2014-06-30 20:17:35] VERBOSE[32392] pbx.c: – Executing [s@macro-exten-vm:18] Set
(“SIP/123456-00004915”, “DIALSTATUS=BUSY”) in new stack [2014-06-30 20:17:35] DEBUG[32392] app_macro.c: Executed application: Set [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘DIALSTATUS’ is ‘BUSY’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘ARG3’ is ‘1’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘DIALSTATUS’ is ‘BUSY’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘ARG4’ is ‘1’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘DIALSTATUS’ is ‘BUSY’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘ARG5’ is ‘1’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Expression result is ‘1’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Launching ‘ExecIf’ [2014-06-30 20:17:35] VERBOSE[32392] pbx.c: – Executing [s@macro-exten-vm:19] ExecIf
(“SIP/123456-00004915”, “1?MacroExit()”) in new stack [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Launching ‘Set’ [2014-06-30 20:17:35] VERBOSE[32392] pbx.c: – Executing [101@from-did-direct:3] Set
(“SIP/123456-00004915”, “__PICKUPMARK=”) in new stack [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘DIALSTATUS’ is ‘BUSY’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Expression result is ‘0’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Launching ‘GotoIf’ [2014-06-30 20:17:35] VERBOSE[32392] pbx.c: – Executing [101@from-did-direct:4] GotoIf
(“SIP/123456-00004915”, “0?ext-local,vmu101,1”) in new stack [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Not taking any branch [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘DIALSTATUS’ is ‘BUSY’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Expression result is ‘1’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Launching ‘GotoIf’ [2014-06-30 20:17:35] VERBOSE[32392] pbx.c: – Executing [101@from-did-direct:5] GotoIf
(“SIP/123456-00004915”, “1?ext-local,vmb101,1”) in new stack [2014-06-30 20:17:35] VERBOSE[32392] pbx.c: – Goto (ext-local,vmb101,1) [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘IVR_RETVM’ is NULL [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Launching ‘Macro’ [2014-06-30 20:17:35] VERBOSE[32392] pbx.c: – Executing [vmb101@ext-local:1] Macro
(“SIP/123456-00004915”, “vm,101,BUSY,”) in new stack [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Launching ‘Macro’ [2014-06-30 20:17:35] VERBOSE[32392] pbx.c: – Executing [s@macro-vm:1] Macro("SIP/123456-
00004915", “user-callerid,SKIPTTL”) in new stack [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘UNIQUEID’ is ‘1404159446.4576’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Launching ‘Set’ [2014-06-30 20:17:35] VERBOSE[32392] pbx.c: – Executing [s@macro-user-callerid:1] Set
(“SIP/123456-00004915”, “TOUCH_MONITOR=1404159446.4576”) in new stack [2014-06-30 20:17:35] DEBUG[32392] app_macro.c: Executed application: Set [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘AMPUSER’ is ‘’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Expression result is ‘1’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Function result is ‘5401112222’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘AMPUSER’ is ‘’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Function result is ‘5401112222’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Launching ‘Set’ [2014-06-30 20:17:35] VERBOSE[32392] pbx.c: – Executing [s@macro-user-callerid:2] Set
(“SIP/123456-00004915”, “AMPUSER=5401112222”) in new stack [2014-06-30 20:17:35] DEBUG[32392] app_macro.c: Executed application: Set [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Evaluating ‘CHANNEL’ (from ‘CHANNEL}’ len 7) [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘CHANNEL’ is ‘SIP/123456-00004915’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Function result is ‘’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘AMPUSERCIDNAME’ is ‘’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Function result is ‘0’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Expression result is ‘0’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Launching ‘GotoIf’ [2014-06-30 20:17:35] VERBOSE[32392] pbx.c: – Executing [s@macro-user-callerid:3] GotoIf
(“SIP/123456-00004915”, “0?report”) in new stack [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Not taking any branch [2014-06-30 20:17:35] DEBUG[32392] app_macro.c: Executed application: GotoIf [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘REALCALLERIDNUM’ is ‘5401112222’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Expression result is ‘0’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Function result is ‘5401112222’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Launching ‘ExecIf’ [2014-06-30 20:17:35] VERBOSE[32392] pbx.c: – Executing [s@macro-user-callerid:4] ExecIf
(“SIP/123456-00004915”, “0?Set(REALCALLERIDNUM=5401112222)”) in new stack [2014-06-30 20:17:35] DEBUG[32392] app_macro.c: Executed application: ExecIf [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Evaluating ‘REALCALLERIDNUM:1:2’ (from
‘REALCALLERIDNUM:1:2}" = “”’ len 19) [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘REALCALLERIDNUM’ is ‘5401112222’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Expression result is ‘0’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Evaluating ‘CALLERID(number)’ (from 'CALLERID
(number)})’ len 16) [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Function result is ‘5401112222’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘REALCALLERIDNUM’ is ‘5401112222’ [2014-06-30 20:17:35] DEBUG[32392] db.c: Unable to find key ‘5401112222/user’ in family
‘DEVICE’ [2014-06-30 20:17:35] DEBUG[32392] func_db.c: DB: DEVICE/5401112222/user not found in
database. [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Function result is ‘’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Launching ‘Set’ [2014-06-30 20:17:35] VERBOSE[32392] pbx.c: – Executing [s@macro-user-callerid:5] Set
(“SIP/123456-00004915”, “AMPUSER=”) in new stack [2014-06-30 20:17:35] DEBUG[32392] app_macro.c: Executed application: Set [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘AMPUSER’ is ‘’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Expression result is ‘0’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Launching ‘GotoIf’ [2014-06-30 20:17:35] VERBOSE[32392] pbx.c: – Executing [s@macro-user-callerid:6] GotoIf
(“SIP/123456-00004915”, “0?limit”) in new stack [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Not taking any branch [2014-06-30 20:17:35] DEBUG[32392] app_macro.c: Executed application: GotoIf [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘AMPUSER’ is ‘’ [2014-06-30 20:17:35] DEBUG[32392] db.c: Unable to find key ‘/cidname’ in family ‘AMPUSER’ [2014-06-30 20:17:35] DEBUG[32392] func_db.c: DB: AMPUSER//cidname not found in database. [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Function result is ‘’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Launching ‘Set’ [2014-06-30 20:17:35] VERBOSE[32392] pbx.c: – Executing [s@macro-user-callerid:7] Set
(“SIP/123456-00004915”, “AMPUSERCIDNAME=”) in new stack [2014-06-30 20:17:35] DEBUG[32392] app_macro.c: Executed application: Set [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘AMPUSERCIDNAME’ is ‘’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Expression result is ‘1’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Launching ‘GotoIf’ [2014-06-30 20:17:35] VERBOSE[32392] pbx.c: – Executing [s@macro-user-callerid:8] GotoIf
(“SIP/123456-00004915”, “1?report”) in new stack [2014-06-30 20:17:35] VERBOSE[32392] pbx.c: – Goto (macro-user-callerid,s,16) [2014-06-30 20:17:35] DEBUG[32392] app_macro.c: Executed application: GotoIf [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘ARG1’ is ‘SKIPTTL’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘ARG1’ is ‘SKIPTTL’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Expression result is ‘1’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Launching ‘GotoIf’ [2014-06-30 20:17:35] VERBOSE[32392] pbx.c: – Executing [s@macro-user-callerid:16] GotoIf
(“SIP/123456-00004915”, “1?continue”) in new stack [2014-06-30 20:17:35] VERBOSE[32392] pbx.c: – Goto (macro-user-callerid,s,30) [2014-06-30 20:17:35] DEBUG[32392] app_macro.c: Executed application: GotoIf [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Function result is ‘5401112222’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Launching ‘Set’ [2014-06-30 20:17:35] VERBOSE[32392] pbx.c: – Executing [s@macro-user-callerid:30] Set
(“SIP/123456-00004915”, “CALLERID(number)=5401112222”) in new stack [2014-06-30 20:17:35] DEBUG[32392] app_macro.c: Executed application: Set [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Function result is ‘+15401112222’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Launching ‘Set’ [2014-06-30 20:17:35] VERBOSE[32392] pbx.c: – Executing [s@macro-user-callerid:31] Set
(“SIP/123456-00004915”, “CALLERID(name)=+15401112222”) in new stack [2014-06-30 20:17:35] DEBUG[32392] app_macro.c: Executed application: Set [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Function result is ‘5401112222’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Launching ‘Set’ [2014-06-30 20:17:35] VERBOSE[32392] pbx.c: – Executing [s@macro-user-callerid:32] Set
(“SIP/123456-00004915”, “CDR(cnum)=5401112222”) in new stack [2014-06-30 20:17:35] DEBUG[32392] app_macro.c: Executed application: Set [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Function result is ‘+15401112222’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Launching ‘Set’ [2014-06-30 20:17:35] VERBOSE[32392] pbx.c: – Executing [s@macro-user-callerid:33] Set
(“SIP/123456-00004915”, “CDR(cnam)=+15401112222”) in new stack [2014-06-30 20:17:35] DEBUG[32392] app_macro.c: Executed application: Set [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Evaluating ‘CHANNEL(language)’ (from 'CHANNEL
(language)}’ len 17) [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Function result is ‘en’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Function result is ‘en’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Launching ‘Set’ [2014-06-30 20:17:35] VERBOSE[32392] pbx.c: – Executing [s@macro-user-callerid:34] Set
(“SIP/123456-00004915”, “CHANNEL(language)=en”) in new stack [2014-06-30 20:17:35] DEBUG[32392] app_macro.c: Executed application: Set [2014-06-30 20:17:35] DEBUG[32392] app_macro.c: Executed application: Macro [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘VM_GAIN’ is ‘12’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Expression result is ‘1’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘VM_GAIN’ is ‘12’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Function result is ‘g(12)’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Launching ‘Set’ [2014-06-30 20:17:35] VERBOSE[32392] pbx.c: – Executing [s@macro-vm:2] Set("SIP/123456-
00004915", “VMGAIN=g(12)”) in new stack [2014-06-30 20:17:35] DEBUG[32392] app_macro.c: Executed application: Set [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Launching ‘Macro’ [2014-06-30 20:17:35] VERBOSE[32392] pbx.c: – Executing [s@macro-vm:3] Macro("SIP/123456-
00004915", “blkvm-check,”) in new stack [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘BLKVM_CHANNEL’ is NULL [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Function result is ‘(null)’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Launching ‘Set’ [2014-06-30 20:17:35] VERBOSE[32392] pbx.c: – Executing [s@macro-blkvm-check:1] Set
(“SIP/123456-00004915”, “GOSUB_RETVAL=”) in new stack [2014-06-30 20:17:35] DEBUG[32392] app_macro.c: Executed application: Set [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘GOSUB_RETVAL’ is ‘’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘CC_RECALL’ is NULL [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Expression result is ‘0’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Launching ‘ExecIf’ [2014-06-30 20:17:35] VERBOSE[32392] pbx.c: – Executing [s@macro-blkvm-check:2] ExecIf
(“SIP/123456-00004915”, “0?Set(GOSUB_RETVAL=TRUE)”) in new stack [2014-06-30 20:17:35] DEBUG[32392] app_macro.c: Executed application: ExecIf [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Evaluating ‘GOSUB_RETVAL’ (from 'GOSUB_RETVAL}"=""
& “${CC_RECALL}”=“1”’ len 12) [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘GOSUB_RETVAL’ is ‘’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Evaluating ‘CC_RECALL’ (from ‘CC_RECALL}"=“1”’ len
(“SIP/123456-00004915”, “”) in new stack [2014-06-30 20:17:35] DEBUG[32392] app_macro.c: Executed application: Macro [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘GOSUB_RETVAL’ is ‘’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Expression result is ‘1’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Launching ‘GotoIf’ [2014-06-30 20:17:35] VERBOSE[32392] pbx.c: – Executing [s@macro-vm:4] GotoIf("SIP/123456-
00004915", “1?vmx,1”) in new stack [2014-06-30 20:17:35] VERBOSE[32392] pbx.c: – Goto (macro-vm,vmx,1) [2014-06-30 20:17:35] DEBUG[32392] app_macro.c: Executed application: GotoIf [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘ARG1’ is ‘101’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Launching ‘Set’ [2014-06-30 20:17:35] VERBOSE[32392] pbx.c: – Executing [vmx@macro-vm:1] Set("SIP/123456-
00004915", “MEXTEN=101”) in new stack [2014-06-30 20:17:35] DEBUG[32392] app_macro.c: Executed application: Set [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘ARG2’ is ‘BUSY’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Launching ‘Set’ [2014-06-30 20:17:35] VERBOSE[32392] pbx.c: – Executing [vmx@macro-vm:2] Set("SIP/123456-
00004915", “MMODE=BUSY”) in new stack [2014-06-30 20:17:35] DEBUG[32392] app_macro.c: Executed application: Set [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘ARG3’ is ‘’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Launching ‘Set’ [2014-06-30 20:17:35] VERBOSE[32392] pbx.c: – Executing [vmx@macro-vm:3] Set("SIP/123456-
00004915", “RETVM=”) in new stack [2014-06-30 20:17:35] DEBUG[32392] app_macro.c: Executed application: Set [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘MMODE’ is ‘BUSY’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Expression result is ‘1’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Function result is ‘busy’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Launching ‘Set’ [2014-06-30 20:17:35] VERBOSE[32392] pbx.c: – Executing [vmx@macro-vm:4] Set("SIP/123456-
00004915", “MODE=busy”) in new stack [2014-06-30 20:17:35] DEBUG[32392] app_macro.c: Executed application: Set [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘MEXTEN’ is ‘101’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘MODE’ is ‘busy’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Function result is ‘blocked’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Expression result is ‘1’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Launching ‘GotoIf’ [2014-06-30 20:17:35] VERBOSE[32392] pbx.c: – Executing [vmx@macro-vm:5] GotoIf("SIP/123456
-00004915", “1?chknomsg”) in new stack [2014-06-30 20:17:35] VERBOSE[32392] pbx.c: – Goto (macro-vm,vmx,7) [2014-06-30 20:17:35] DEBUG[32392] app_macro.c: Executed application: GotoIf [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘MMODE’ is ‘BUSY’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Expression result is ‘0’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘MMODE’ is ‘BUSY’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Launching ‘GotoIf’ [2014-06-30 20:17:35] VERBOSE[32392] pbx.c: – Executing [vmx@macro-vm:7] GotoIf("SIP/123456
-00004915", “0?s-BUSY,1”) in new stack [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Not taking any branch [2014-06-30 20:17:35] DEBUG[32392] app_macro.c: Executed application: GotoIf [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘MMODE’ is ‘BUSY’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Expression result is ‘1’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Launching ‘GotoIf’ [2014-06-30 20:17:35] VERBOSE[32392] pbx.c: – Executing [vmx@macro-vm:8] GotoIf("SIP/123456
-00004915", “1?notdirect”) in new stack [2014-06-30 20:17:35] VERBOSE[32392] pbx.c: – Goto (macro-vm,vmx,10) [2014-06-30 20:17:35] DEBUG[32392] app_macro.c: Executed application: GotoIf [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘MEXTEN’ is ‘101’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘MEXTEN’ is ‘101’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘MODE’ is ‘busy’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Function result is ‘blocked’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Launching ‘NoOp’ [2014-06-30 20:17:35] VERBOSE[32392] pbx.c: – Executing [vmx@macro-vm:10] NoOp("SIP/123456-
00004915", “Checking if ext 101 is enabled: blocked”) in new stack [2014-06-30 20:17:35] DEBUG[32392] app_macro.c: Executed application: Noop [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘MEXTEN’ is ‘101’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘MODE’ is ‘busy’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Function result is ‘blocked’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Expression result is ‘1’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘MMODE’ is ‘BUSY’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Launching ‘GotoIf’ [2014-06-30 20:17:35] VERBOSE[32392] pbx.c: – Executing [vmx@macro-vm:11] GotoIf
(“SIP/123456-00004915”, “1?s-BUSY,1”) in new stack [2014-06-30 20:17:35] VERBOSE[32392] pbx.c: – Goto (macro-vm,s-BUSY,1) [2014-06-30 20:17:35] DEBUG[32392] app_macro.c: Executed application: GotoIf [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Launching ‘NoOp’ [2014-06-30 20:17:35] VERBOSE[32392] pbx.c: – Executing [s-BUSY@macro-vm:1] NoOp
(“SIP/123456-00004915”, “BUSY voicemail”) in new stack [2014-06-30 20:17:35] DEBUG[32392] app_macro.c: Executed application: Noop [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘MEXTEN’ is ‘101’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Launching ‘Macro’ [2014-06-30 20:17:35] VERBOSE[32392] pbx.c: – Executing [s-BUSY@macro-vm:2] Macro
(“SIP/123456-00004915”, “get-vmcontext,101”) in new stack [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Result of ‘ARG1’ is ‘101’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Function result is ‘default’ [2014-06-30 20:17:35] DEBUG[32392] pbx.c: Launching ‘Set’ [2014-06-30 20:17:35] VERBOSE[32392] pbx.c: – Executing [s@macro-get-vmcontext:1]
Thanks a lot
Looks very similar - will implement their recs
My current asterisk version is Asterisk 1.8.12, on a debian 7 server. Is it possible to upgrade from source to current version. Or would I simply have to delete old version and start from scratch?
apt-get remove asterisk*
and start from scratch
Please I need your assistant to fix this issue that i have been trying to resolve for 2 weeks now.
My PBX server has a “Wild card TDM800P” card installed in it. Our trunks were configured with dahdi and
call working well too until after a reboot of the server due to IP conflict in the network. Since the reboot, the modules on the card where gone and all the settings attached to the card. when I uninstall and install Dahdi on freePbx dash board and applying configuration I got the message below;
exit: 1
Failed to backup old config: Permission denied
[FATAL] INSERT INTO dahdi_spans (span, framing, definedchans, coding, signalling, switchtype, syncsrc, lbo, pridialplan, prilocaldialplan, group, context) VALUES [nativecode=1064 ** You have an error in your SQL syntax; check the manual that corresponds to your MySQL server version for the right syntax to use near ‘’ at line 1]
Trace Back:
/var/www/html/admin/modules/dahdiconfig/functions.inc.php:1107 die_freepbx()
[0]: INSERT INTO dahdi_spans (span, framing, definedchans, coding, signalling, switchtype, syncsrc, lbo, pridialplan, prilocaldialplan, group, context) VALUES [nativecode=1064 ** You have an error in your SQL syntax; check the manual that corresponds to your MySQL server version for the right syntax to use near ‘’ at line 1]
/var/www/html/admin/modules/dahdiconfig/functions.inc.php:461 dahdi_cards->write_spans()
/var/www/html/admin/modules/dahdiconfig/functions.inc.php:184 dahdi_cards->load()
/var/www/html/admin/modules/dahdiconfig/functions.inc.php:34 dahdi_cards->dahdi_cards()
/var/lib/asterisk/bin/retrieve_conf:399 dahdiconfig_conf->dahdiconfig_conf()
1 error(s) occurred, you should view the notification log on the dashboard or main screen to check for more details.
Close Close Retry Retry
I can see the cards showing on “service dahdi start”. I have done a new installation on the pbx server, but the drivers did not automatically load as I have read.
Please what can I do to fix this issue and restore communication thru the trunk.
Thank you.
Thanks Dicko. For future releases of asterisk, is this the path to upgrade - remove the prior installation and then install from source? This installation I am setting up is intended for my office - the upgrade process looks disruptive - especially all the endpoint and SIP settings, etc. Any thoughts?
Not too much of a problem, after you have purged the .deb’s then just
./configure;make menuselect;make;make install
will get you updated, check the output of the make install bit, as you might ned to fix some libraries if you changed your selections in make menuselct.
Th process should have no impact on any previous settings.
Thanks a lot
I successfully installed asterisk 12 after removing and purging the previous version. I would now like to upgrade Freepbx 2.11 to version 12. The documentation suggests an upgrade path from module admin - but I could not make this work. I would appreciate any suggestions
I would wait for it to leave beta or post your perceived bugs.