Severe problems after upgrade to 2.4

Hello,

the upgrade itself worked the way it should, as it seems. But now I’ve got problems calling extensions, from internal as well as from trunk. The problem seems to be related to dialparties.agi as it always returnes with no extensions to dial.

See below for what I find in the asterisk full log when calling ext. 3345 from ext. 3344. If the parts before this are important I can post them as well. Similar things happen on trunk originated calls routed to a ring group. Note the (suspicious?) NOTICE “pbx.c: No such label ‘normdial’ in extension ‘s’ in context ‘macro-dial’” during dialparties.agi operation. System is Debian 4 with asterisk 1.2.13, freepbx running on php5/fcgi with the same permissions as asterisk.

As this issue is somewhat urgent (was very confident and forgot to make backups :expressionless: ) I appreciate any help. Thank you!

Andreas

[...] Feb 23 14:14:06 VERBOSE[16639] logger.c: -- Goto (macro-dial,s,3) Feb 23 14:14:06 VERBOSE[16639] logger.c: -- Executing AGI("SIP/3344-081b24c8", "dialparties.agi") in new stack Feb 23 14:14:06 VERBOSE[16639] logger.c: -- Launched AGI Script /usr/share/asterisk/agi-bin/dialparties.agi Feb 23 14:14:06 VERBOSE[16639] logger.c: dialparties.agi: Starting New Dialparties.agi Feb 23 14:14:06 DEBUG[16643] manager.c: Manager received command 'login' Feb 23 14:14:06 VERBOSE[16643] logger.c: == Parsing '/etc/asterisk/manager.conf': Feb 23 14:14:06 VERBOSE[16643] logger.c: == Parsing '/etc/asterisk/manager.conf': Found Feb 23 14:14:06 VERBOSE[16643] logger.c: == Parsing '/etc/asterisk/manager_additional.conf': Feb 23 14:14:06 VERBOSE[16643] logger.c: == Parsing '/etc/asterisk/manager_additional.conf': Found Feb 23 14:14:06 VERBOSE[16643] logger.c: == Parsing '/etc/asterisk/manager_custom.conf': Feb 23 14:14:06 VERBOSE[16643] logger.c: == Parsing '/etc/asterisk/manager_custom.conf': Found Feb 23 14:14:06 DEBUG[16643] acl.c: 0.0.0.0/0.0.0.0/0.0.0.0 appended to acl for peer Feb 23 14:14:06 DEBUG[16643] acl.c: 127.0.0.1/255.255.255.0/255.255.255.0 appended to acl for peer Feb 23 14:14:06 DEBUG[16643] acl.c: ##### Testing 127.0.0.1 with 0.0.0.0 Feb 23 14:14:06 DEBUG[16643] acl.c: ##### Testing 127.0.0.1 with 127.0.0.0 Feb 23 14:14:06 VERBOSE[16643] logger.c: == Manager 'admin' logged on from 127.0.0.1 Feb 23 14:14:06 VERBOSE[16639] logger.c: dialparties.agi: Caller ID name is 'Andreas - PC Puut' number is '3344' Feb 23 14:14:06 VERBOSE[16639] logger.c: dialparties.agi: Methodology of ring is 'none' Feb 23 14:14:06 VERBOSE[16639] logger.c: -- dialparties.agi: Added extension 3345 to extension map Feb 23 14:14:06 DEBUG[16639] db.c: Unable to find key '3345' in family 'CF' Feb 23 14:14:06 VERBOSE[16639] logger.c: -- dialparties.agi: Extension 3345 cf is disabled Feb 23 14:14:06 DEBUG[16639] db.c: Unable to find key '3345' in family 'DND' Feb 23 14:14:06 VERBOSE[16639] logger.c: -- dialparties.agi: Extension 3345 do not disturb is disabled Feb 23 14:14:06 DEBUG[16639] db.c: Unable to find key '3345' in family 'CFB' Feb 23 14:14:06 DEBUG[16639] db.c: Unable to find key '3345' in family 'CFU' Feb 23 14:14:06 DEBUG[16643] manager.c: Manager received command 'ExtensionState' Feb 23 14:14:06 VERBOSE[16639] logger.c: -- dialparties.agi: dbset CALLTRACE/3345 to 3344 Feb 23 14:14:06 VERBOSE[16639] logger.c: -- dialparties.agi: Filtered ARG3: 3345 Feb 23 14:14:06 NOTICE[16639] pbx.c: No such label 'normdial' in extension 's' in context 'macro-dial' Feb 23 14:14:06 DEBUG[16643] manager.c: Manager received command 'Logoff' Feb 23 14:14:06 VERBOSE[16643] logger.c: == Manager 'admin' logged off from 127.0.0.1 Feb 23 14:14:06 VERBOSE[16639] logger.c: -- AGI Script dialparties.agi completed, returning 0 Feb 23 14:14:06 VERBOSE[16639] logger.c: -- Executing NoOp("SIP/3344-081b24c8", "Returned from dialparties with no extensions to call and DIALSTATUS: ") in new stack Feb 23 14:14:06 VERBOSE[16639] logger.c: -- Executing Set("SIP/3344-081b24c8", "SV_DIALSTATUS=") in new stack Feb 23 14:14:06 DEBUG[16639] pbx.c: Expression result is '0' Feb 23 14:14:06 DEBUG[16639] pbx.c: Expression result is '0' Feb 23 14:14:06 DEBUG[16639] pbx.c: Expression result is '0' Feb 23 14:14:06 VERBOSE[16639] logger.c: -- Executing GosubIf("SIP/3344-081b24c8", "0?docfu|1") in new stack Feb 23 14:14:06 DEBUG[16639] pbx.c: Expression result is '0' Feb 23 14:14:06 DEBUG[16639] pbx.c: Expression result is '0' Feb 23 14:14:06 DEBUG[16639] pbx.c: Expression result is '0' Feb 23 14:14:06 VERBOSE[16639] logger.c: -- Executing GosubIf("SIP/3344-081b24c8", "0?docfb|1") in new stack Feb 23 14:14:06 VERBOSE[16639] logger.c: -- Executing Set("SIP/3344-081b24c8", "DIALSTATUS=") in new stack Feb 23 14:14:06 VERBOSE[16639] logger.c: -- Executing NoOp("SIP/3344-081b24c8", "Voicemail is novm") in new stack Feb 23 14:14:06 DEBUG[16639] pbx.c: Expression result is '1' Feb 23 14:14:06 VERBOSE[16639] logger.c: -- Executing GotoIf("SIP/3344-081b24c8", "1?s-|1") in new stack Feb 23 14:14:06 VERBOSE[16639] logger.c: -- Goto (macro-exten-vm,s-,1) Feb 23 14:14:06 VERBOSE[16639] logger.c: -- Executing PlayTones("SIP/3344-081b24c8", "congestion") in new stack Feb 23 14:14:06 DEBUG[16639] channel.c: Prodding channel 'SIP/3344-081b24c8' Feb 23 14:14:06 VERBOSE[16639] logger.c: -- Executing Congestion("SIP/3344-081b24c8", "10") in new stack Feb 23 14:14:06 VERBOSE[16639] logger.c: == Spawn extension (macro-exten-vm, s-, 2) exited non-zero on 'SIP/3344-081b24c8' in macro 'exten-vm' Feb 23 14:14:06 VERBOSE[16639] logger.c: == Spawn extension (macro-exten-vm, s-, 2) exited non-zero on 'SIP/3344-081b24c8' Feb 23 14:14:06 DEBUG[16155] channel.c: Avoiding initial deadlock for 'SIP/3344-081b24c8' Feb 23 14:14:06 DEBUG[16163] chan_sip.c: Failed to grab lock, trying again... Feb 23 14:14:06 DEBUG[16639] pbx.c: Function result is '"Andreas - PC Puut" <3344>' Feb 23 14:14:06 DEBUG[16639] pbx.c: Function result is '3344' Feb 23 14:14:06 DEBUG[16639] pbx.c: Function result is '3345' Feb 23 14:14:06 DEBUG[16639] pbx.c: Function result is 'from-internal' Feb 23 14:14:06 DEBUG[16639] pbx.c: Function result is 'SIP/3344-081b24c8' Feb 23 14:14:06 DEBUG[16639] pbx.c: Function result is '(null)' Feb 23 14:14:06 DEBUG[16639] pbx.c: Function result is 'Congestion' Feb 23 14:14:06 DEBUG[16639] pbx.c: Function result is '10' Feb 23 14:14:06 DEBUG[16639] pbx.c: Function result is '2008-02-23 14:14:06' Feb 23 14:14:06 DEBUG[16639] pbx.c: Function result is '(null)' Feb 23 14:14:06 DEBUG[16639] pbx.c: Function result is '2008-02-23 14:14:06' Feb 23 14:14:06 DEBUG[16639] pbx.c: Function result is '0' Feb 23 14:14:06 DEBUG[16639] pbx.c: Function result is '0' Feb 23 14:14:06 DEBUG[16639] pbx.c: Function result is 'NO ANSWER' Feb 23 14:14:06 DEBUG[16639] pbx.c: Function result is 'DOCUMENTATION' Feb 23 14:14:06 DEBUG[16639] pbx.c: Function result is '(null)' Feb 23 14:14:06 DEBUG[16639] pbx.c: Function result is '1203772446.3' Feb 23 14:14:06 DEBUG[16639] pbx.c: Function result is '(null)' Feb 23 14:14:06 DEBUG[16639] chan_sip.c: update_call_counter(3344) - decrement call limit counter Feb 23 14:14:06 DEBUG[16163] chan_sip.c: Stopping retransmission on 'YzExY2QzZTE5Njc1YWU0YmVlMjkyNWJmOWQ1Yzc5MzI.' of Response 2: Match Not Found Feb 23 14:14:09 NOTICE[16163] chan_sip.c: Unknown SIP command 'PUBLISH' from '192.168.240.75' Feb 23 14:14:11 DEBUG[16163] chan_sip.c: Auto destroying call '[email protected]' Feb 23 14:14:16 WARNING[16169] chan_oh323.c: Gatekeeper discovery failed. Feb 23 14:14:16 VERBOSE[16169] logger.c: -- Retrying gatekeeper registration.

I would guess that the proper extensions.conf file from the module is not getting linked to extensions.conf, which would happen if you have your own copy of extensions.conf in /etc/asterisk or have overridden maro-dial prior. Check the notification panel concerning errors from retrieve_conf and check the extensions.conf to make sure it is a symlink back to the module directory.

Thank you very much for this hint and your quick answer.You’re right, I’m indeed lacking the symlinks and have files there instead. I guess something has gone wrong during the initial install. It took me some time to figure out how to set up FreePBX on Debian, and struggled on permission issues until I learnt the PHP application really has to run with the same permissions as asterisk (and fitted my apache2 with some fcgi and suexec stuff then).

I will fix that in the next some minutes and hopefully my extensions will work again. As there are wide similarities between the original debian files which are still in place and the ones provided by the core module, that theory is somewhat consistent with producing a failure after the upgrade.

Let me tell you that I really enjoy using FreePBX. Thank’s for that cool piece of software!

Report comes later!

cu, Andreas

Thanks again, that really fixed it. Make use of my donation.

Must correct the files I found weren’t the debian originals but most probably the result of my initial struggle. Can you answer me another question and tell me if the *.orig copies in admin/modules/core/etc and their symlinks are used in some situation or are they just a backup?

those orig files are a really old mistake that should not have gotten in there. If you use the online module admin on the latest versions of FreePBX, they will get removed.